CI run for sopass

Radicle repository id rad:z4ExzU1jYHivznhtDPvuTL9z5Ebit

Table of Contents

Run log

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


INFO: Block devices
NAME   MAJ:MIN RM   SIZE RO TYPE MOUNTPOINTS
sr0     11:0    1   370K  0 rom  
vda    254:0    0   100G  0 disk 
├─vda1 254:1    0   953M  0 part 
└─vda2 254:2    0  99.1G  0 part /
vdb    254:16   0   2.9M  1 disk 
vdc    254:32   0   970K  1 disk 
vdd    254:48   0   9.3G  0 disk 
vde    254:64   0  46.6G  0 disk 
vdf    254:80   0 346.1M  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-08-16 05:22 ./
-rw-r--r-- _rad/_rad      1493 2025-08-16 05:22 ./plan.yaml
-rwxr-xr-x _rad/_rad   3003032 2025-08-16 05:22 ./run-ci
INFO: Extracted files:
  1477809      4 drwxr-xr-x   2 1001     1001         4096 Aug 16 05:22 .
  1477882      4 -rw-r--r--   1 1001     1001         1493 Aug 16 05:22 ./plan.yaml
  1477883   2936 -rwxr-xr-x   1 1001     1001      3003032 Aug 16 05:22 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-08-16T05:22:53Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:22:53Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:22:53Z INFO  ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:22:53Z WARN  ambient_execute_plan] ambient-execute-plan starts
[2025-08-16T05:22:53Z ERROR ambient_execute_plan] ambient-execute-plan starts
RunnablePlan::from_file: filename=plan.yaml
steps:
- action: mkdir
  pathname: /workspace
- action: mkdir
  pathname: /workspace/artifacts
- action: tar_extract
  archive: /dev/vdc
  directory: /workspace/src
- action: tar_extract
  archive: /dev/vdf
  directory: /workspace/deps
- action: tar_extract
  archive: /dev/vde
  directory: /workspace/cache
- action: spawn
  argv:
  - find
  - /workspace
  - '-maxdepth'
  - '2'
  - '-ls'
- action: cargo_fmt
- action: cargo_clippy
- action: cargo_build
- action: shell
  shell: |
    make sopass.html
    mv sopass.html /workspace/artifacts/sopass.html
- action: shell
  shell: |
    export DEBEMAIL=liw@liw.fi
    export DEBFULLNAME="Lars Wirzenius"
    export HOME=/root
    export PATH="/root/.cargo/bin:/bin:/sbin"
    export SOPASS_LOG=trace
    export CARGO_TARGET_DIR=/workspace/cache
    export CARGO_HOME=/workspace/deps

    make OFFLINE="--offline --locked"
    git clean -fdx

    V="$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')"
    T="$(date -u "+%Y%m%dT%H%M%S")"
    version="$V.ci$T-1"
    dch -v "$version" "CI build under Ambient."
    dch -r ''
- action: deb
- action: tar_create
  archive: /dev/vde
  directory: /workspace/cache
- action: tar_create
  archive: /dev/vdd
  directory: /workspace/artifacts
executor_drive: /dev/vdb
source_drive: /dev/vdc
artifact_drive: /dev/vdd
cache_drive: /dev/vde
deps_drive: /dev/vdf
workspace_dir: /workspace
source_dir: /workspace/src
deps_dir: /workspace/deps
cache_dir: /workspace/cache
artifacts_dir: /workspace/artifacts


RUN: Action Mkdir {
    pathname: "/workspace",
}
[2025-08-16T05:22:53Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace",
    }
RUN: Action finished OK
RUN: Action Mkdir {
    pathname: "/workspace/artifacts",
}
[2025-08-16T05:22:53Z 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:22:53Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-08-16T05:22:53Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-08-16T05:22:53Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdf",
    directory: "/workspace/deps",
}
[2025-08-16T05:22:53Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-08-16T05:22:54Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-08-16T05:22:55Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-08-16T05:22:55Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-08-16T05:22:55Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-08-16T05:22:55Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
    argv: [
        "find",
        "/workspace",
        "-maxdepth",
        "2",
        "-ls",
    ],
}
[2025-08-16T05:22:55Z 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:22 /workspace
  3932162      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:22 /workspace/artifacts
  3932657      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:22 /workspace/deps
  3932659     56 -rw-r--r--   1 root     root        57344 Aug 16 05:22 /workspace/deps/.global-cache
  3932658      0 -rw-r--r--   1 root     root            0 Aug 16 05:22 /workspace/deps/.package-cache-mutate
  3932660      0 -rw-r--r--   1 root     root            0 Aug 16 05:22 /workspace/deps/.package-cache
  3932661      4 drwxr-xr-x   5 root     root         4096 Aug 16 05:22 /workspace/deps/registry
  3932163      4 drwxr-xr-x   6 root     root         4096 Aug 16 05:22 /workspace/src
  3932181      4 -rw-r--r--   1 root     root          289 Aug 16 05:22 /workspace/src/pass-to-sopass.sh
  3932164      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:22 /workspace/src/.radicle
  3932182      4 -rw-r--r--   1 root     root         3822 Aug 16 05:22 /workspace/src/NEWS.md
  3932656     24 -rw-r--r--   1 root     root        22098 Aug 16 05:22 /workspace/src/Cargo.lock
  3932200     20 -rw-r--r--   1 root     root        17262 Aug 16 05:22 /workspace/src/sopass.md
  3932199      4 -rw-r--r--   1 root     root          537 Aug 16 05:22 /workspace/src/Cargo.toml
  3932197      4 -rw-r--r--   1 root     root           82 Aug 16 05:22 /workspace/src/sopass.yaml
  3932198      4 -rw-r--r--   1 root     root           20 Aug 16 05:22 /workspace/src/.gitignore
  3932654      4 -rw-r--r--   1 root     root          447 Aug 16 05:22 /workspace/src/sopass.py
  3932655      4 -rw-r--r--   1 root     root          480 Aug 16 05:22 /workspace/src/Makefile
  3932201      4 drwxr-xr-x   8 root     root         4096 Aug 16 05:22 /workspace/src/.git
  3932183      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:22 /workspace/src/debian
  3932194      4 -rw-r--r--   1 root     root         1377 Aug 16 05:22 /workspace/src/sopass.1
  3932195      4 -rw-r--r--   1 root     root         1109 Aug 16 05:22 /workspace/src/README.md
  3932166      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:22 /workspace/src/src
  3932196      4 -rw-r--r--   1 root     root          206 Aug 16 05:22 /workspace/src/sopass.subplot
  3942103      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:22 /workspace/cache
RUN: Action finished OK
RUN: Action CargoFmt
[2025-08-16T05:22:55Z DEBUG ambient_ci::action] Plan::execute: CargoFmt
SPAWN: argv=["cargo", "fmt", "--check"]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
RUN: Action finished OK
RUN: Action CargoClippy
[2025-08-16T05:22:55Z DEBUG ambient_ci::action] Plan::execute: CargoClippy
SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
   Compiling proc-macro2 v1.0.92
   Compiling unicode-ident v1.0.14
   Compiling libc v0.2.169
    Checking memchr v2.7.4
   Compiling serde v1.0.219
    Checking bitflags v2.6.0
    Checking itoa v1.0.14
   Compiling rustix v0.38.42
   Compiling anyhow v1.0.98
    Checking hashbrown v0.15.2
    Checking ryu v1.0.18
    Checking equivalent v1.0.1
   Compiling quote v1.0.38
    Checking utf8parse v0.2.2
    Checking anstyle-parse v0.2.6
   Compiling syn v2.0.92
    Checking aho-corasick v1.1.3
   Compiling getrandom v0.3.3
    Checking indexmap v2.7.1
    Checking colorchoice v1.0.3
    Checking regex-syntax v0.8.5
    Checking linux-raw-sys v0.4.14
    Checking is_terminal_polyfill v1.70.1
   Compiling version_check v0.9.5
    Checking anstyle v1.0.10
    Checking anstyle-query v1.1.2
    Checking cfg-if v1.0.0
   Compiling rustix v1.0.7
   Compiling serde_yml v0.0.12
    Checking anstream v0.6.18
    Checking regex-automata v0.4.9
   Compiling serde_json v1.0.140
   Compiling thiserror v2.0.12
    Checking option-ext v0.2.0
    Checking linux-raw-sys v0.9.4
   Compiling thiserror v1.0.69
    Checking dirs-sys v0.4.1
    Checking terminal_size v0.4.1
    Checking libyml v0.0.5
    Checking regex v1.11.1
    Checking clap_lex v0.7.4
    Checking unsafe-libyaml v0.2.11
    Checking once_cell v1.20.2
    Checking strsim v0.11.1
   Compiling serde_derive v1.0.219
   Compiling thiserror-impl v2.0.12
   Compiling thiserror-impl v1.0.69
    Checking fastrand v2.3.0
    Checking log v0.4.22
   Compiling heck v0.5.0
   Compiling clap_derive v4.5.18
    Checking env_filter v0.1.3
    Checking tempfile v3.20.0
    Checking clap_builder v4.5.23
    Checking directories v5.0.1
    Checking dirs-sys-next v0.1.2
    Checking humantime v2.1.0
    Checking env_logger v0.11.6
    Checking directories-next v2.0.0
    Checking clap v4.5.23
    Checking serde_yaml v0.9.34+deprecated
    Checking confy v0.6.1
    Checking clingwrap v0.2.0
    Checking sopass v0.5.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.76s
RUN: Action finished OK
RUN: Action CargoBuild
[2025-08-16T05:23:09Z 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 memchr v2.7.4
   Compiling bitflags v2.6.0
   Compiling libc v0.2.169
   Compiling serde v1.0.219
   Compiling ryu v1.0.18
   Compiling hashbrown v0.15.2
   Compiling itoa v1.0.14
   Compiling utf8parse v0.2.2
   Compiling equivalent v1.0.1
   Compiling anstyle-parse v0.2.6
   Compiling aho-corasick v1.1.3
   Compiling indexmap v2.7.1
   Compiling regex-syntax v0.8.5
   Compiling linux-raw-sys v0.4.14
   Compiling colorchoice v1.0.3
   Compiling anstyle v1.0.10
   Compiling cfg-if v1.0.0
   Compiling anstyle-query v1.1.2
   Compiling is_terminal_polyfill v1.70.1
   Compiling anstream v0.6.18
   Compiling regex-automata v0.4.9
   Compiling rustix v0.38.42
   Compiling anyhow v1.0.98
   Compiling linux-raw-sys v0.9.4
   Compiling option-ext v0.2.0
   Compiling libyml v0.0.5
   Compiling dirs-sys v0.4.1
   Compiling rustix v1.0.7
   Compiling terminal_size v0.4.1
   Compiling getrandom v0.3.3
   Compiling once_cell v1.20.2
   Compiling clap_lex v0.7.4
   Compiling fastrand v2.3.0
   Compiling strsim v0.11.1
   Compiling unsafe-libyaml v0.2.11
   Compiling regex v1.11.1
   Compiling log v0.4.22
   Compiling serde_yaml v0.9.34+deprecated
   Compiling env_filter v0.1.3
   Compiling tempfile v3.20.0
   Compiling clap_builder v4.5.23
   Compiling serde_yml v0.0.12
   Compiling thiserror v1.0.69
   Compiling thiserror v2.0.12
   Compiling serde_json v1.0.140
   Compiling directories v5.0.1
   Compiling dirs-sys-next v0.1.2
   Compiling humantime v2.1.0
   Compiling directories-next v2.0.0
   Compiling clap v4.5.23
   Compiling env_logger v0.11.6
   Compiling confy v0.6.1
   Compiling clingwrap v0.2.0
   Compiling sopass v0.5.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.85s
RUN: Action finished OK
RUN: Action Shell {
    shell: "make sopass.html\nmv sopass.html /workspace/artifacts/sopass.html\n",
}
[2025-08-16T05:23:23Z DEBUG ambient_ci::action] Plan::execute: Shell {
        shell: "make sopass.html\nmv sopass.html /workspace/artifacts/sopass.html\n",
    }
SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nmake sopass.html\nmv sopass.html /workspace/artifacts/sopass.html\n\n"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
+ make sopass.html
subplot docgen sopass.subplot -o sopass.html
 INFO Starting Subplot
 INFO Subplot finished successfully
+ mv sopass.html /workspace/artifacts/sopass.html
RUN: Action finished OK
RUN: Action Shell {
    shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nmake OFFLINE=\"--offline --locked\"\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
}
[2025-08-16T05:23:26Z DEBUG ambient_ci::action] Plan::execute: Shell {
        shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nmake OFFLINE=\"--offline --locked\"\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
    }
SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nmake OFFLINE=\"--offline --locked\"\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n\n"]
       cwd=/workspace/src (exists? true)
       extra_env=[]
+ export DEBEMAIL=liw@liw.fi
+ DEBEMAIL=liw@liw.fi
+ export 'DEBFULLNAME=Lars Wirzenius'
+ DEBFULLNAME='Lars Wirzenius'
+ export HOME=/root
+ HOME=/root
+ export PATH=/root/.cargo/bin:/bin:/sbin
+ PATH=/root/.cargo/bin:/bin:/sbin
+ export SOPASS_LOG=trace
+ SOPASS_LOG=trace
+ export CARGO_TARGET_DIR=/workspace/cache
+ CARGO_TARGET_DIR=/workspace/cache
+ export CARGO_HOME=/workspace/deps
+ CARGO_HOME=/workspace/deps
+ make 'OFFLINE=--offline --locked'
subplot docgen sopass.subplot -o sopass.html
 INFO Starting Subplot
 INFO Subplot finished successfully
cargo clippy --offline --locked --all-targets -- -Dwarnings
    Checking sopass v0.5.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.83s
cargo build --offline --locked --all-targets
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.09s
cargo test --offline --locked --all-targets
    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
     Running unittests src/lib.rs (/workspace/cache/debug/deps/sopass-c8927301952d39d6)

running 0 tests

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

     Running unittests src/main.rs (/workspace/cache/debug/deps/sopass-782358d950ba1deb)

running 0 tests

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

cargo test --offline --locked --doc
    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
   Doc-tests sopass

running 0 tests

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

cargo doc --offline --locked --no-deps
 Documenting sopass v0.5.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.92s
   Generated /workspace/cache/doc/sopass/index.html
rm -f test.py test.log
subplot codegen sopass.subplot -o test.py
 INFO Starting Subplot
 INFO Subplot finished successfully
SOPASS_LOG=trace python3 test.py --log test.log
srcdir /workspace/src
datadir /tmp/tmpxjcbcn6s
scenario: Showing value that does not exist fails
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file my.key
  step: when I run sopass --store xyzzy init --name primary --key my.key
  step: when I try to run sopass --store xyzzy value show foo
  step: then command fails
  step: then stderr contains "foo"
  step: then stdout is exactly ""
scenario: Manages certificates
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file my.key
  step: given file other.key
  step: given file other.cert
  step: when I run sopass --store xyzzy init --name primary --key my.key
  step: when I run sopass --store xyzzy cert list
  step: then stdout is exactly "primary\n"
  step: when I run sopass --store xyzzy cert add --name secondary --cert other.cert
  step: when I run sopass --store xyzzy cert list
  step: then stdout contains "primary"
  step: then stdout contains "secondary"
  step: when I run mv other.key xyzzy/default.key
  step: when I run rm my.key
  step: when I run sopass --store xyzzy cert list
  step: then stdout contains "primary"
  step: then stdout contains "secondary"
  step: when I run sopass --store xyzzy cert remove primary
  step: when I run sopass --store xyzzy cert list
  step: then stdout doesn't contain "primary"
  step: then stdout contains "secondary"
  step: when I try to run sopass --store xyzzy cert remove secondary
  step: then command fails
  step: then stderr contains "secondary"
  step: when I run sopass --store xyzzy cert list
  step: then stdout contains "secondary"
scenario: Initializes the password store
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file my.key
  step: then directory xyzzy does not exist
  step: when I run sopass --store xyzzy init --name primary --key my.key
  step: then file xyzzy/values.sopass exists
scenario: Manages values
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file my.key
  step: when I run sopass --store xyzzy init --name primary --key my.key
  step: when I run sopass --store xyzzy value list
  step: then stdout is exactly ""
  step: given file value.dat
  step: when I run sopass --store xyzzy value add foo --file value.dat
  step: when I run sopass --store xyzzy value list
  step: then stdout is exactly "foo\n"
  step: when I run sopass --store xyzzy value show foo
  step: then stdout is exactly "bar\n"
  step: when I run sopass --store xyzzy value remove foo
  step: then stdout is exactly ""
  step: given file add-stdin.sh
  step: when I run sh add-stdin.sh
  step: when I run sopass --store xyzzy value list
  step: then stdout is exactly "foo\n"
  step: when I run sopass --store xyzzy value show foo
  step: then stdout is exactly "bar\n"
  step: when I run sopass --store xyzzy value remove foo
  step: then stdout is exactly ""
scenario: Loads default configuration file
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file .config/sopass/sopass.yml from default.yaml
  step: when I run env HOME=. sopass config
  step: then stdout contains ""store": "/over/the/rainbow""
scenario: Renames values
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file my.key
  step: when I run sopass --store xyzzy init --name primary --key my.key
  step: given file value.dat
  step: when I run sopass --store xyzzy value add foo --file value.dat
  step: when I run sopass --store xyzzy value add foobar --file value.dat
  step: when I try to run sopass --store xyzzy value rename ghost yo
  step: then command fails
  step: then stderr contains "ghost"
  step: when I try to run sopass --store xyzzy value rename foo foobar
  step: then command fails
  step: then stderr contains "foobar"
  step: when I run sopass --store xyzzy value rename foo yo
  step: when I run sopass --store xyzzy value list
  step: then stdout is exactly "foobar\nyo\n"
scenario: Reports its version
  step: given an installed sopass
bindir /workspace/cache/debug
  step: when I run sopass --version
  step: then stdout matches regex ^sopass \\d+\\.\\d+\\.\\d+$
  step: when I run sopass version
  step: then stdout matches regex ^sopass \\d+\\.\\d+\\.\\d+$
scenario: Imports from a file
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file my.key
  step: given file value.dat
  step: given file yo.dat
  step: when I run sopass --store xyzzy init --name primary --key my.key
  step: when I run sopass --store xyzzy value add foo --file value.dat
  step: when I run sopass --store xyzzy value add bar --file value.dat
  step: when I run sopass --store xyzzy value add yo --file value.dat
  step: when I run sopass --store xyzzy export store.json
  step: when I run sopass --store new init --name primary --key my.key
  step: when I run sopass --store new value add foo --file yo.dat
  step: when I run sopass --store new value add bar --file yo.dat
  step: when I run sopass --store new import store.json
  step: when I run sopass --store new value list
  step: then stdout contains "foo"
  step: then stdout contains "bar"
  step: then stdout contains "yo"
  step: when I run sopass --store new value show foo
  step: then stdout is exactly "bar\n"
  step: when I run sopass --store new value show bar
  step: then stdout is exactly "bar\n"
  step: when I run sopass --store new value show yo
  step: then stdout is exactly "bar\n"
scenario: Reports a default configuration
  step: given an installed sopass
bindir /workspace/cache/debug
  step: when I run sopass config
  step: then stdout contains ""store": "/"
  step: then stdout contains ""sop": ""
  step: then stdout contains ""key_file": ""
  step: then file .config/sopass/sopass.yml does not exist
scenario: Rejects unknown field in configuration file
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file unknown.yaml
  step: when I try to run sopass --config unknown.yaml config
  step: then command fails
  step: then stderr contains "unknown"
scenario: Exports store to file
  step: given an installed sopass
bindir /workspace/cache/debug
  step: given file my.key
  step: when I run sopass --store xyzzy init --name primary --key my.key
  step: given file value.dat
  step: when I run sopass --store xyzzy value add foo --file value.dat
  step: when I run sopass --store xyzzy export store.json
  step: when I run cat store.json
  step: then file store.json contains ""certs":"
  step: then file store.json contains ""primary":"
  step: then file store.json contains ""kv":"
  step: then file store.json contains ""foo":"
scenario: Loads specified configuration file
  step: given an installed sopass
bindir /workspace/cache/debug
  step: when I try to run sopass --config custom.yaml config
  step: then command fails
  step: then stderr contains "custom.yaml"
  step: given file custom.yaml
  step: when I run sopass --config custom.yaml config
  step: then stdout contains ""sop": "soppy""
OK, all scenarios finished successfully
rm -f test.py test.log
+ git clean -fdx
Removing sopass.html
++ dpkg-parsechangelog -SVersion
++ sed 's/-[^-]*$//'
+ V=0.5.0
++ date -u +%Y%m%dT%H%M%S
+ T=20250816T052331
+ version=0.5.0.ci20250816T052331-1
+ dch -v 0.5.0.ci20250816T052331-1 'CI build under Ambient.'
+ dch -r ''
RUN: Action finished OK
RUN: Action Deb
[2025-08-16T05:23:32Z DEBUG ambient_ci::action] Plan::execute: Deb
SPAWN: argv=["/bin/bash", "-c", "#!/bin/bash\nset -xeuo pipefail\n\necho \"PATH at start: $PATH\"\nexport PATH=\"/root/.cargo/bin:$PATH\"\nexport CARGO_HOME=/workspace/deps\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\n/bin/env\n\ncommand -v cargo\ncommand -v rustc\n\ncargo --version\nrustc --version\n\n# Get name and version of source package.\nname=\"$(dpkg-parsechangelog -SSource)\"\nversion=\"$(dpkg-parsechangelog -SVersion)\"\n\n# Get upstream version: everything before the last dash.\nuv=\"$(echo \"$version\" | sed 's/-[^-]*$//')\"\n\n# Files that will be created.\narch=\"$(dpkg --print-architecture)\"\norig=\"../${name}_${uv}.orig.tar.xz\"\ndeb=\"../${name}_${version}_${arch}.deb\"\nchanges=\"../${name}_${version}_${arch}.changes\"\n\n# Create \"upstream tarball\".\ngit archive HEAD | xz >\"$orig\"\n\n# Build package.\ndpkg-buildpackage -us -uc\n\n# Dump some information to make it easier to visually verify\n# everything looks OK. Also, test the package with the lintian tool.\n\nls -l ..\nfor x in ../*.deb; do dpkg -c \"$x\"; done\n# FIXME: disabled while this prevents radicle-native-ci deb from being built.\n# lintian -i --allow-root --fail-on warning ../*.changes\n\n# Move files to artifacts directory.\nmv ../*_* /workspace/artifacts\n        "]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
+ echo 'PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ export PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
+ export CARGO_HOME=/workspace/deps
+ CARGO_HOME=/workspace/deps
+ export DEBEMAIL=liw@liw.fi
+ DEBEMAIL=liw@liw.fi
+ export 'DEBFULLNAME=Lars Wirzenius'
+ DEBFULLNAME='Lars Wirzenius'
+ /bin/env
DEBFULLNAME=Lars Wirzenius
CARGO_TARGET_DIR=/workspace/cache
PWD=/workspace/src
SYSTEMD_EXEC_PID=292
LANG=C.UTF-8
CARGO_HOME=/workspace/deps
DEBEMAIL=liw@liw.fi
INVOCATION_ID=12c921f211bf486a84fd5a0c9c43d663
SHLVL=2
JOURNAL_STREAM=8:12707
PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
OLDPWD=/
_=/bin/env
+ command -v cargo
/root/.cargo/bin/cargo
+ command -v rustc
/root/.cargo/bin/rustc
+ cargo --version
cargo 1.89.0 (c24e10642 2025-06-23)
+ rustc --version
rustc 1.89.0 (29483883e 2025-08-04)
++ dpkg-parsechangelog -SSource
+ name=sopass
++ dpkg-parsechangelog -SVersion
+ version=0.5.0.ci20250816T052331-1
++ sed 's/-[^-]*$//'
++ echo 0.5.0.ci20250816T052331-1
+ uv=0.5.0.ci20250816T052331
++ dpkg --print-architecture
+ arch=amd64
+ orig=../sopass_0.5.0.ci20250816T052331.orig.tar.xz
+ deb=../sopass_0.5.0.ci20250816T052331-1_amd64.deb
+ changes=../sopass_0.5.0.ci20250816T052331-1_amd64.changes
+ git archive HEAD
+ xz
+ dpkg-buildpackage -us -uc
dpkg-buildpackage: info: source package sopass
dpkg-buildpackage: info: source version 0.5.0.ci20250816T052331-1
dpkg-buildpackage: info: source distribution unstable
dpkg-buildpackage: info: source changed by Lars Wirzenius <liw@liw.fi>
dpkg-buildpackage: info: host architecture amd64
 dpkg-source --before-build .
 debian/rules clean
dh clean
   dh_auto_clean
   dh_clean
 dpkg-source -b .
dpkg-source: info: using source format '3.0 (quilt)'
dpkg-source: info: building sopass using existing ./sopass_0.5.0.ci20250816T052331.orig.tar.xz
dpkg-source: info: building sopass in sopass_0.5.0.ci20250816T052331-1.debian.tar.xz
dpkg-source: info: building sopass in sopass_0.5.0.ci20250816T052331-1.dsc
 debian/rules build
dh build
   dh_update_autotools_config
   dh_autoreconf
   dh_auto_configure
   debian/rules override_dh_auto_build
make[1]: Entering directory '/workspace/src'
true
make[1]: Leaving directory '/workspace/src'
   debian/rules override_dh_auto_test
make[1]: Entering directory '/workspace/src'
echo tests are disabled, for now
tests are disabled, for now
make[1]: Leaving directory '/workspace/src'
   create-stamp debian/debhelper-build-stamp
 debian/rules binary
dh binary
   dh_testroot
   dh_prep
   debian/rules override_dh_auto_install
make[1]: Entering directory '/workspace/src'
cargo install --offline --locked --target x86_64-unknown-linux-musl --path=. --root=debian/sopass/usr
  Installing sopass v0.5.0 (/workspace/src)
   Compiling proc-macro2 v1.0.92
   Compiling unicode-ident v1.0.14
   Compiling libc v0.2.169
   Compiling memchr v2.7.4
   Compiling bitflags v2.6.0
   Compiling serde v1.0.219
   Compiling quote v1.0.38
   Compiling hashbrown v0.15.2
   Compiling equivalent v1.0.1
   Compiling syn v2.0.92
   Compiling ryu v1.0.18
   Compiling itoa v1.0.14
   Compiling rustix v0.38.42
   Compiling anyhow v1.0.98
   Compiling utf8parse v0.2.2
   Compiling anstyle-parse v0.2.6
   Compiling indexmap v2.7.1
   Compiling aho-corasick v1.1.3
   Compiling getrandom v0.3.3
   Compiling rustix v1.0.7
   Compiling colorchoice v1.0.3
   Compiling is_terminal_polyfill v1.70.1
   Compiling cfg-if v1.0.0
   Compiling linux-raw-sys v0.4.14
   Compiling version_check v0.9.5
   Compiling anstyle-query v1.1.2
   Compiling anstyle v1.0.10
   Compiling regex-syntax v0.8.5
   Compiling serde_yml v0.0.12
   Compiling anstream v0.6.18
   Compiling serde_derive v1.0.219
   Compiling regex-automata v0.4.9
   Compiling thiserror v1.0.69
   Compiling serde_json v1.0.140
   Compiling option-ext v0.2.0
   Compiling linux-raw-sys v0.9.4
   Compiling thiserror v2.0.12
   Compiling regex v1.11.1
   Compiling dirs-sys v0.4.1
   Compiling libyml v0.0.5
   Compiling terminal_size v0.4.1
   Compiling thiserror-impl v2.0.12
   Compiling thiserror-impl v1.0.69
   Compiling unsafe-libyaml v0.2.11
   Compiling once_cell v1.20.2
   Compiling heck v0.5.0
   Compiling log v0.4.22
   Compiling clap_lex v0.7.4
   Compiling fastrand v2.3.0
   Compiling strsim v0.11.1
   Compiling tempfile v3.20.0
   Compiling clap_builder v4.5.23
   Compiling serde_yaml v0.9.34+deprecated
   Compiling env_filter v0.1.3
   Compiling clap_derive v4.5.18
   Compiling directories v5.0.1
   Compiling dirs-sys-next v0.1.2
   Compiling humantime v2.1.0
   Compiling env_logger v0.11.6
   Compiling directories-next v2.0.0
   Compiling clingwrap v0.2.0
   Compiling confy v0.6.1
   Compiling clap v4.5.23
   Compiling sopass v0.5.0 (/workspace/src)
    Finished `release` profile [optimized] target(s) in 37.03s
  Installing debian/sopass/usr/bin/sopass
   Installed package `sopass v0.5.0 (/workspace/src)` (executable `sopass`)
warning: be sure to add `debian/sopass/usr/bin` to your PATH to be able to run the installed binaries
find debian -name ".crates*" -delete
make[1]: Leaving directory '/workspace/src'
   dh_installdocs
   dh_installchangelogs
   dh_lintian
   dh_perl
   dh_link
   dh_strip_nondeterminism
   dh_compress
   dh_fixperms
   dh_missing
   dh_strip
   dh_makeshlibs
   dh_shlibdeps
dpkg-shlibdeps: warning: couldn't parse dynamic symbol definition: no symbols
   dh_installdeb
   dh_gencontrol
dpkg-gencontrol: warning: Depends field of package sopass: substitution variable ${shlibs:Depends} used, but is not defined
dpkg-gencontrol: warning: Depends field of package sopass: substitution variable ${shlibs:Depends} used, but is not defined
   dh_md5sums
   dh_builddeb
dpkg-deb: building package 'sopass-dbgsym' in '../sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb'.
dpkg-deb: building package 'sopass' in '../sopass_0.5.0.ci20250816T052331-1_amd64.deb'.
 dpkg-genbuildinfo -O../sopass_0.5.0.ci20250816T052331-1_amd64.buildinfo
 dpkg-genchanges -O../sopass_0.5.0.ci20250816T052331-1_amd64.changes
dpkg-genchanges: info: including full source code in upload
 dpkg-source --after-build .
dpkg-buildpackage: info: full upload (original source is included)
+ ls -l ..
total 1084
drwxr-xr-x 2 root root   4096 Aug 16 05:23 artifacts
drwxr-xr-x 6 root root   4096 Aug 16 05:23 cache
drwxr-xr-x 3 root root   4096 Aug 16 05:22 deps
-rw-r--r-- 1 root root 122624 Aug 16 05:24 sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb
-rw-r--r-- 1 root root   2292 Aug 16 05:23 sopass_0.5.0.ci20250816T052331-1.debian.tar.xz
-rw-r--r-- 1 root root    926 Aug 16 05:23 sopass_0.5.0.ci20250816T052331-1.dsc
-rw-r--r-- 1 root root   5756 Aug 16 05:24 sopass_0.5.0.ci20250816T052331-1_amd64.buildinfo
-rw-r--r-- 1 root root   2331 Aug 16 05:24 sopass_0.5.0.ci20250816T052331-1_amd64.changes
-rw-r--r-- 1 root root 917684 Aug 16 05:24 sopass_0.5.0.ci20250816T052331-1_amd64.deb
-rw-r--r-- 1 root root  25840 Aug 16 05:23 sopass_0.5.0.ci20250816T052331.orig.tar.xz
drwxr-xr-x 6 root root   4096 Aug 16 05:23 src
+ for x in ../*.deb
+ dpkg -c ../sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb
drwxr-xr-x root/root         0 2025-08-16 05:23 ./
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/lib/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/lib/debug/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/lib/debug/.build-id/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/lib/debug/.build-id/eb/
-rw-r--r-- root/root    628048 2025-08-16 05:23 ./usr/lib/debug/.build-id/eb/cb6b3ac7fc89a39250a96a7aaf22f6a2c10a19.debug
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/doc/
lrwxrwxrwx root/root         0 2025-08-16 05:23 ./usr/share/doc/sopass-dbgsym -> sopass
+ for x in ../*.deb
+ dpkg -c ../sopass_0.5.0.ci20250816T052331-1_amd64.deb
drwxr-xr-x root/root         0 2025-08-16 05:23 ./
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/bin/
-rwxr-xr-x root/root   2847392 2025-08-16 05:23 ./usr/bin/sopass
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/doc/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/doc/sopass/
-rw-r--r-- root/root       276 2025-08-16 05:23 ./usr/share/doc/sopass/changelog.Debian.gz
-rw-r--r-- root/root      1150 2025-08-16 05:22 ./usr/share/doc/sopass/copyright
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/lintian/
drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/lintian/overrides/
-rw-r--r-- root/root       173 2025-08-16 05:22 ./usr/share/lintian/overrides/sopass
+ mv ../sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb ../sopass_0.5.0.ci20250816T052331-1.debian.tar.xz ../sopass_0.5.0.ci20250816T052331-1.dsc ../sopass_0.5.0.ci20250816T052331-1_amd64.buildinfo ../sopass_0.5.0.ci20250816T052331-1_amd64.changes ../sopass_0.5.0.ci20250816T052331-1_amd64.deb ../sopass_0.5.0.ci20250816T052331.orig.tar.xz /workspace/artifacts
RUN: Action finished OK
RUN: Action TarCreate {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-08-16T05:24:12Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vde",
        ),
        root: Some(
            "/workspace/cache",
        ),
        size: None,
    }
[2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
[2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] create archive file /dev/vde
[2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
[2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
[2025-08-16T05:24:13Z 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:24:13Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
        archive: "/dev/vdd",
        directory: "/workspace/artifacts",
    }
[2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vdd",
        ),
        root: Some(
            "/workspace/artifacts",
        ),
        size: None,
    }
[2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
[2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
[2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
[2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
[2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
RUN: Action finished OK
ambient-execute-plan ends
EXIT CODE: 0
[2025-08-16T05:24:20Z INFO  ambient] ambient ends successfully

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "push",
  "repository": {
    "id": "rad:z4ExzU1jYHivznhtDPvuTL9z5Ebit",
    "name": "sopass",
    "description": "A command line password manager using SOP",
    "private": false,
    "default_branch": "main",
    "delegates": [
      "did:key:z6MkgEMYod7Hxfy9qCvDv5hYHkZ4ciWmLFgfvm3Wn1b2w2FV"
    ]
  },
  "pusher": {
    "id": "did:key:z6MkkYez6QAFWvmdudjrfMXySu1NTpSrraJotCJFwGgkmgx8",
    "alias": "ci0"
  },
  "before": "bc586aa5b7069a57a1fc2a72fe3e8998b8d82e95",
  "after": "bc586aa5b7069a57a1fc2a72fe3e8998b8d82e95",
  "branch": "main",
  "commits": [
    "bc586aa5b7069a57a1fc2a72fe3e8998b8d82e95"
  ]
}

Ambient stdout

[2025-08-16T05:22:38Z INFO  ambient] ambient starts
[2025-08-16T05:22:38Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-08-16T05:22:38Z DEBUG ambient] load files named with --config: /tmp/.tmp6ABgUG/ambient_extra_values.yaml
[2025-08-16T05:22:38Z DEBUG ambient] complete configuration: Config {
        tmpdir: "/srv/tmp",
        image_store: "/home/_rad/.local/state/ambient-ci/images",
        projects: "/home/_rad/ambient-projects.yaml",
        state: "/srv/ambient-state",
        rsync_target: None,
        rsync_target_base: Some(
            "ci@http.liw.fi:/srv/http/doc.liw.fi",
        ),
        rsync_target_map: None,
        dput_target: Some(
            "apt.liw.fi",
        ),
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            10000000000,
        ),
        cache_max_size: Byte(
            50000000000,
        ),
        qemu: QemuConfig {
            cpus: 4,
            memory: Byte(
                8000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-08-16T05:22:38Z DEBUG ambient] configuration: Config {
        tmpdir: "/srv/tmp",
        image_store: "/home/_rad/.local/state/ambient-ci/images",
        projects: "/home/_rad/ambient-projects.yaml",
        state: "/srv/ambient-state",
        rsync_target: None,
        rsync_target_base: Some(
            "ci@http.liw.fi:/srv/http/doc.liw.fi",
        ),
        rsync_target_map: None,
        dput_target: Some(
            "apt.liw.fi",
        ),
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            10000000000,
        ),
        cache_max_size: Byte(
            50000000000,
        ),
        qemu: QemuConfig {
            cpus: 4,
            memory: Byte(
                8000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-08-16T05:22:38Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/sopass/meta.yaml
[2025-08-16T05:22:38Z DEBUG ambient_ci::run] latest commit: "bc586aa5b7069a57a1fc2a72fe3e8998b8d82e95"
[2025-08-16T05:22:38Z DEBUG ambient_ci::run] is a git repository
[2025-08-16T05:22:38Z DEBUG ambient_ci::run] git repository is clean
[2025-08-16T05:22:38Z DEBUG ambient_ci::run] current (HEAD) commit: bc586aa5b7069a57a1fc2a72fe3e8998b8d82e95
[2025-08-16T05:22:38Z DEBUG ambient_ci::run] no dry run requested
[2025-08-16T05:22:38Z DEBUG ambient_ci::run] forced run requested
[2025-08-16T05:22:38Z DEBUG ambient_ci::run] run? true
[2025-08-16T05:22:38Z INFO  ambient_ci::run] project sopass: running CI
[2025-08-16T05:22:38Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-08-16T05:22:38Z DEBUG ambient_ci::action] Plan::execute: CargoFetch {
        sourcedir: "/tmp/.tmpbmGaJ3/src",
        dependenciesdir: "/srv/ambient-state/sopass/dependencies",
    }
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/sopass/dependencies", "cargo", "fetch", "--locked"]
       cwd=/tmp/.tmpbmGaJ3/src (exists? true)
       extra_env=[]
    Updating crates.io index
 Downloading crates ...
  Downloaded thiserror v2.0.12
  Downloaded unicode-ident v1.0.14
  Downloaded windows-targets v0.52.6
  Downloaded is_terminal_polyfill v1.70.1
  Downloaded anstyle v1.0.10
  Downloaded option-ext v0.2.0
  Downloaded equivalent v1.0.1
  Downloaded redox_users v0.4.6
  Downloaded strsim v0.11.1
  Downloaded getrandom v0.2.15
  Downloaded aho-corasick v1.1.3
  Downloaded clap_lex v0.7.4
  Downloaded windows-targets v0.48.5
  Downloaded heck v0.5.0
  Downloaded colorchoice v1.0.3
  Downloaded regex-automata v0.4.9
  Downloaded clingwrap v0.2.0
  Downloaded windows_aarch64_gnullvm v0.52.6
  Downloaded hashbrown v0.15.2
  Downloaded rustix v1.0.7
  Downloaded libc v0.2.169
  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 serde_json v1.0.140
  Downloaded wasi v0.14.2+wasi-0.2.4
  Downloaded windows_i686_gnullvm v0.52.6
  Downloaded anyhow v1.0.98
  Downloaded ryu v1.0.18
  Downloaded windows_aarch64_msvc v0.52.6
  Downloaded windows_x86_64_gnullvm v0.52.6
  Downloaded indexmap v2.7.1
  Downloaded directories v5.0.1
  Downloaded unsafe-libyaml v0.2.11
  Downloaded serde_yaml v0.9.34+deprecated
  Downloaded confy v0.6.1
  Downloaded wasi v0.11.0+wasi-snapshot-preview1
  Downloaded libredox v0.1.3
  Downloaded anstream v0.6.18
  Downloaded libyml v0.0.5
  Downloaded terminal_size v0.4.1
  Downloaded clap_derive v4.5.18
  Downloaded clap v4.5.23
  Downloaded serde v1.0.219
  Downloaded regex-syntax v0.8.5
  Downloaded windows_x86_64_gnullvm v0.48.5
  Downloaded thiserror-impl v1.0.69
  Downloaded thiserror v1.0.69
  Downloaded dirs-sys-next v0.1.2
  Downloaded clap_builder v4.5.23
  Downloaded serde_yml v0.0.12
  Downloaded windows_aarch64_gnullvm v0.48.5
  Downloaded regex v1.11.1
  Downloaded log v0.4.22
  Downloaded itoa v1.0.14
  Downloaded memchr v2.7.4
  Downloaded version_check v0.9.5
  Downloaded dirs-sys v0.4.1
  Downloaded directories-next v2.0.0
  Downloaded anstyle-parse v0.2.6
  Downloaded anstyle-query v1.1.2
  Downloaded rustix v0.38.42
  Downloaded anstyle-wincon v3.0.6
  Downloaded utf8parse v0.2.2
  Downloaded humantime v2.1.0
  Downloaded linux-raw-sys v0.4.14
  Downloaded windows_aarch64_msvc v0.48.5
  Downloaded syn v2.0.92
  Downloaded windows_i686_gnu v0.48.5
  Downloaded windows_i686_msvc v0.48.5
  Downloaded windows_x86_64_gnu v0.48.5
  Downloaded windows_x86_64_msvc v0.48.5
  Downloaded winapi v0.3.9
  Downloaded serde_derive v1.0.219
  Downloaded fastrand v2.3.0
  Downloaded getrandom v0.3.3
  Downloaded once_cell v1.20.2
  Downloaded bitflags v2.6.0
  Downloaded tempfile v3.20.0
  Downloaded proc-macro2 v1.0.92
  Downloaded env_filter v0.1.3
  Downloaded env_logger v0.11.6
  Downloaded cfg-if v1.0.0
  Downloaded r-efi v5.3.0
  Downloaded linux-raw-sys v0.9.4
  Downloaded wit-bindgen-rt v0.39.0
  Downloaded errno v0.3.10
  Downloaded quote v1.0.38
  Downloaded thiserror-impl v2.0.12
  Downloaded windows-sys v0.59.0
  Downloaded winapi-i686-pc-windows-gnu v0.4.0
  Downloaded windows-sys v0.48.0
  Downloaded winapi-x86_64-pc-windows-gnu v0.4.0
[2025-08-16T05:22:41Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmppFEymT/src.tar
[2025-08-16T05:22:41Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmppFEymT/deps.tar
[2025-08-16T05:22:41Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-08-16T05:22:41Z DEBUG ambient_ci::run] create_executor_vdrive: plan=RunnablePlan {
        steps: [
            Mkdir {
                pathname: "/workspace",
            },
            Mkdir {
                pathname: "/workspace/artifacts",
            },
            TarExtract {
                archive: "/dev/vdc",
                directory: "/workspace/src",
            },
            TarExtract {
                archive: "/dev/vdf",
                directory: "/workspace/deps",
            },
            TarExtract {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
            Spawn {
                argv: [
                    "find",
                    "/workspace",
                    "-maxdepth",
                    "2",
                    "-ls",
                ],
            },
            CargoFmt,
            CargoClippy,
            CargoBuild,
            Shell {
                shell: "make sopass.html\nmv sopass.html /workspace/artifacts/sopass.html\n",
            },
            Shell {
                shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nmake OFFLINE=\"--offline --locked\"\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
            },
            Deb,
            TarCreate {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
            TarCreate {
                archive: "/dev/vdd",
                directory: "/workspace/artifacts",
            },
        ],
        executor_drive: Some(
            "/dev/vdb",
        ),
        source_drive: Some(
            "/dev/vdc",
        ),
        artifact_drive: Some(
            "/dev/vdd",
        ),
        cache_drive: Some(
            "/dev/vde",
        ),
        deps_drive: Some(
            "/dev/vdf",
        ),
        workspace_dir: Some(
            "/workspace",
        ),
        source_dir: Some(
            "/workspace/src",
        ),
        deps_dir: Some(
            "/workspace/deps",
        ),
        cache_dir: Some(
            "/workspace/cache",
        ),
        artifacts_dir: Some(
            "/workspace/artifacts",
        ),
    }
[2025-08-16T05:22:41Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-08-16T05:22:41Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmppFEymT/ambient-execute-plan/run-ci
[2025-08-16T05:22:41Z DEBUG ambient_ci::run] copy OK: true
[2025-08-16T05:22:41Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmppFEymT/executor.tar
[2025-08-16T05:22:41Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/sopass/run.log
[2025-08-16T05:22:41Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/sopass, exists? true
[2025-08-16T05:22:41Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/sopass/run.log
[2025-08-16T05:22:41Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/sopass/run.log OK
[2025-08-16T05:22:41Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-08-16T05:22:41Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-08-16T05:22:41Z 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:22:41Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-08-16T05:22:41Z 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:22:41Z DEBUG ambient_ci::qemu] run QEMU
[2025-08-16T05:22:41Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-08-16T05:22:41Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmpxAxDyS/.tmpz4Y6aF/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-08-16T05:22:41Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-08-16T05:22:41Z 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:22:41Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpBkhT57/meta-data
[2025-08-16T05:22:41Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpBkhT57/user-data
[2025-08-16T05:22:42Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmpxAxDyS/.tmpz4Y6aF/console.log
[2025-08-16T05:22:42Z DEBUG ambient_ci::util] create file /srv/tmp/.tmpxAxDyS/.tmpz4Y6aF/console.log
[2025-08-16T05:22:42Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/sopass/run.log
[2025-08-16T05:22:42Z DEBUG ambient_ci::util] create file /srv/ambient-state/sopass/run.log
[2025-08-16T05:22:42Z 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/.tmpxAxDyS/.tmpz4Y6aF/console.log",
            "-serial",
            "file:/srv/ambient-state/sopass/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/.tmpxAxDyS/.tmpz4Y6aF/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/srv/tmp/.tmpxAxDyS/.tmpz4Y6aF/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmppFEymT/executor.tar,readonly=on",
            "-cdrom",
            "/srv/tmp/.tmpxAxDyS/.tmpz4Y6aF/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmppFEymT/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmppFEymT/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmppFEymT/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/srv/tmp/.tmppFEymT/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-08-16T05:24:17Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-08-16T05:24:17Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-08-16T05:22:53Z TRACE ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:22:53Z DEBUG ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:22:53Z INFO  ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:22:53Z WARN  ambient_execute_plan] ambient-execute-plan starts
    [2025-08-16T05:22:53Z ERROR ambient_execute_plan] ambient-execute-plan starts
    RunnablePlan::from_file: filename=plan.yaml
    steps:
    - action: mkdir
      pathname: /workspace
    - action: mkdir
      pathname: /workspace/artifacts
    - action: tar_extract
      archive: /dev/vdc
      directory: /workspace/src
    - action: tar_extract
      archive: /dev/vdf
      directory: /workspace/deps
    - action: tar_extract
      archive: /dev/vde
      directory: /workspace/cache
    - action: spawn
      argv:
      - find
      - /workspace
      - '-maxdepth'
      - '2'
      - '-ls'
    - action: cargo_fmt
    - action: cargo_clippy
    - action: cargo_build
    - action: shell
      shell: |
        make sopass.html
        mv sopass.html /workspace/artifacts/sopass.html
    - action: shell
      shell: |
        export DEBEMAIL=liw@liw.fi
        export DEBFULLNAME="Lars Wirzenius"
        export HOME=/root
        export PATH="/root/.cargo/bin:/bin:/sbin"
        export SOPASS_LOG=trace
        export CARGO_TARGET_DIR=/workspace/cache
        export CARGO_HOME=/workspace/deps
    
        make OFFLINE="--offline --locked"
        git clean -fdx
    
        V="$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')"
        T="$(date -u "+%Y%m%dT%H%M%S")"
        version="$V.ci$T-1"
        dch -v "$version" "CI build under Ambient."
        dch -r ''
    - action: deb
    - action: tar_create
      archive: /dev/vde
      directory: /workspace/cache
    - action: tar_create
      archive: /dev/vdd
      directory: /workspace/artifacts
    executor_drive: /dev/vdb
    source_drive: /dev/vdc
    artifact_drive: /dev/vdd
    cache_drive: /dev/vde
    deps_drive: /dev/vdf
    workspace_dir: /workspace
    source_dir: /workspace/src
    deps_dir: /workspace/deps
    cache_dir: /workspace/cache
    artifacts_dir: /workspace/artifacts
    
    
    RUN: Action Mkdir {
        pathname: "/workspace",
    }
    [2025-08-16T05:22:53Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace",
        }
    RUN: Action finished OK
    RUN: Action Mkdir {
        pathname: "/workspace/artifacts",
    }
    [2025-08-16T05:22:53Z 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:22:53Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-08-16T05:22:53Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-08-16T05:22:53Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
    [2025-08-16T05:22:53Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-08-16T05:22:54Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-08-16T05:22:55Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-08-16T05:22:55Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-08-16T05:22:55Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-08-16T05:22:55Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
    [2025-08-16T05:22:55Z 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:22 /workspace
      3932162      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:22 /workspace/artifacts
      3932657      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:22 /workspace/deps
      3932659     56 -rw-r--r--   1 root     root        57344 Aug 16 05:22 /workspace/deps/.global-cache
      3932658      0 -rw-r--r--   1 root     root            0 Aug 16 05:22 /workspace/deps/.package-cache-mutate
      3932660      0 -rw-r--r--   1 root     root            0 Aug 16 05:22 /workspace/deps/.package-cache
      3932661      4 drwxr-xr-x   5 root     root         4096 Aug 16 05:22 /workspace/deps/registry
      3932163      4 drwxr-xr-x   6 root     root         4096 Aug 16 05:22 /workspace/src
      3932181      4 -rw-r--r--   1 root     root          289 Aug 16 05:22 /workspace/src/pass-to-sopass.sh
      3932164      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:22 /workspace/src/.radicle
      3932182      4 -rw-r--r--   1 root     root         3822 Aug 16 05:22 /workspace/src/NEWS.md
      3932656     24 -rw-r--r--   1 root     root        22098 Aug 16 05:22 /workspace/src/Cargo.lock
      3932200     20 -rw-r--r--   1 root     root        17262 Aug 16 05:22 /workspace/src/sopass.md
      3932199      4 -rw-r--r--   1 root     root          537 Aug 16 05:22 /workspace/src/Cargo.toml
      3932197      4 -rw-r--r--   1 root     root           82 Aug 16 05:22 /workspace/src/sopass.yaml
      3932198      4 -rw-r--r--   1 root     root           20 Aug 16 05:22 /workspace/src/.gitignore
      3932654      4 -rw-r--r--   1 root     root          447 Aug 16 05:22 /workspace/src/sopass.py
      3932655      4 -rw-r--r--   1 root     root          480 Aug 16 05:22 /workspace/src/Makefile
      3932201      4 drwxr-xr-x   8 root     root         4096 Aug 16 05:22 /workspace/src/.git
      3932183      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:22 /workspace/src/debian
      3932194      4 -rw-r--r--   1 root     root         1377 Aug 16 05:22 /workspace/src/sopass.1
      3932195      4 -rw-r--r--   1 root     root         1109 Aug 16 05:22 /workspace/src/README.md
      3932166      4 drwxr-xr-x   3 root     root         4096 Aug 16 05:22 /workspace/src/src
      3932196      4 -rw-r--r--   1 root     root          206 Aug 16 05:22 /workspace/src/sopass.subplot
      3942103      4 drwxr-xr-x   2 root     root         4096 Aug 16 05:22 /workspace/cache
    RUN: Action finished OK
    RUN: Action CargoFmt
    [2025-08-16T05:22:55Z DEBUG ambient_ci::action] Plan::execute: CargoFmt
    SPAWN: argv=["cargo", "fmt", "--check"]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
    RUN: Action finished OK
    RUN: Action CargoClippy
    [2025-08-16T05:22:55Z DEBUG ambient_ci::action] Plan::execute: CargoClippy
    SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
       Compiling proc-macro2 v1.0.92
       Compiling unicode-ident v1.0.14
       Compiling libc v0.2.169
        Checking memchr v2.7.4
       Compiling serde v1.0.219
        Checking bitflags v2.6.0
        Checking itoa v1.0.14
       Compiling rustix v0.38.42
       Compiling anyhow v1.0.98
        Checking hashbrown v0.15.2
        Checking ryu v1.0.18
        Checking equivalent v1.0.1
       Compiling quote v1.0.38
        Checking utf8parse v0.2.2
        Checking anstyle-parse v0.2.6
       Compiling syn v2.0.92
        Checking aho-corasick v1.1.3
       Compiling getrandom v0.3.3
        Checking indexmap v2.7.1
        Checking colorchoice v1.0.3
        Checking regex-syntax v0.8.5
        Checking linux-raw-sys v0.4.14
        Checking is_terminal_polyfill v1.70.1
       Compiling version_check v0.9.5
        Checking anstyle v1.0.10
        Checking anstyle-query v1.1.2
        Checking cfg-if v1.0.0
       Compiling rustix v1.0.7
       Compiling serde_yml v0.0.12
        Checking anstream v0.6.18
        Checking regex-automata v0.4.9
       Compiling serde_json v1.0.140
       Compiling thiserror v2.0.12
        Checking option-ext v0.2.0
        Checking linux-raw-sys v0.9.4
       Compiling thiserror v1.0.69
        Checking dirs-sys v0.4.1
        Checking terminal_size v0.4.1
        Checking libyml v0.0.5
        Checking regex v1.11.1
        Checking clap_lex v0.7.4
        Checking unsafe-libyaml v0.2.11
        Checking once_cell v1.20.2
        Checking strsim v0.11.1
       Compiling serde_derive v1.0.219
       Compiling thiserror-impl v2.0.12
       Compiling thiserror-impl v1.0.69
        Checking fastrand v2.3.0
        Checking log v0.4.22
       Compiling heck v0.5.0
       Compiling clap_derive v4.5.18
        Checking env_filter v0.1.3
        Checking tempfile v3.20.0
        Checking clap_builder v4.5.23
        Checking directories v5.0.1
        Checking dirs-sys-next v0.1.2
        Checking humantime v2.1.0
        Checking env_logger v0.11.6
        Checking directories-next v2.0.0
        Checking clap v4.5.23
        Checking serde_yaml v0.9.34+deprecated
        Checking confy v0.6.1
        Checking clingwrap v0.2.0
        Checking sopass v0.5.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.76s
    RUN: Action finished OK
    RUN: Action CargoBuild
    [2025-08-16T05:23:09Z 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 memchr v2.7.4
       Compiling bitflags v2.6.0
       Compiling libc v0.2.169
       Compiling serde v1.0.219
       Compiling ryu v1.0.18
       Compiling hashbrown v0.15.2
       Compiling itoa v1.0.14
       Compiling utf8parse v0.2.2
       Compiling equivalent v1.0.1
       Compiling anstyle-parse v0.2.6
       Compiling aho-corasick v1.1.3
       Compiling indexmap v2.7.1
       Compiling regex-syntax v0.8.5
       Compiling linux-raw-sys v0.4.14
       Compiling colorchoice v1.0.3
       Compiling anstyle v1.0.10
       Compiling cfg-if v1.0.0
       Compiling anstyle-query v1.1.2
       Compiling is_terminal_polyfill v1.70.1
       Compiling anstream v0.6.18
       Compiling regex-automata v0.4.9
       Compiling rustix v0.38.42
       Compiling anyhow v1.0.98
       Compiling linux-raw-sys v0.9.4
       Compiling option-ext v0.2.0
       Compiling libyml v0.0.5
       Compiling dirs-sys v0.4.1
       Compiling rustix v1.0.7
       Compiling terminal_size v0.4.1
       Compiling getrandom v0.3.3
       Compiling once_cell v1.20.2
       Compiling clap_lex v0.7.4
       Compiling fastrand v2.3.0
       Compiling strsim v0.11.1
       Compiling unsafe-libyaml v0.2.11
       Compiling regex v1.11.1
       Compiling log v0.4.22
       Compiling serde_yaml v0.9.34+deprecated
       Compiling env_filter v0.1.3
       Compiling tempfile v3.20.0
       Compiling clap_builder v4.5.23
       Compiling serde_yml v0.0.12
       Compiling thiserror v1.0.69
       Compiling thiserror v2.0.12
       Compiling serde_json v1.0.140
       Compiling directories v5.0.1
       Compiling dirs-sys-next v0.1.2
       Compiling humantime v2.1.0
       Compiling directories-next v2.0.0
       Compiling clap v4.5.23
       Compiling env_logger v0.11.6
       Compiling confy v0.6.1
       Compiling clingwrap v0.2.0
       Compiling sopass v0.5.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.85s
    RUN: Action finished OK
    RUN: Action Shell {
        shell: "make sopass.html\nmv sopass.html /workspace/artifacts/sopass.html\n",
    }
    [2025-08-16T05:23:23Z DEBUG ambient_ci::action] Plan::execute: Shell {
            shell: "make sopass.html\nmv sopass.html /workspace/artifacts/sopass.html\n",
        }
    SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nmake sopass.html\nmv sopass.html /workspace/artifacts/sopass.html\n\n"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
    + make sopass.html
    subplot docgen sopass.subplot -o sopass.html
     INFO Starting Subplot
     INFO Subplot finished successfully
    + mv sopass.html /workspace/artifacts/sopass.html
    RUN: Action finished OK
    RUN: Action Shell {
        shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nmake OFFLINE=\"--offline --locked\"\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
    }
    [2025-08-16T05:23:26Z DEBUG ambient_ci::action] Plan::execute: Shell {
            shell: "export DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nmake OFFLINE=\"--offline --locked\"\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n",
        }
    SPAWN: argv=["/bin/bash", "-c", "set -xeuo pipefail\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:/bin:/sbin\"\nexport SOPASS_LOG=trace\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\n\nmake OFFLINE=\"--offline --locked\"\ngit clean -fdx\n\nV=\"$(dpkg-parsechangelog -SVersion | sed 's/-[^-]*$//')\"\nT=\"$(date -u \"+%Y%m%dT%H%M%S\")\"\nversion=\"$V.ci$T-1\"\ndch -v \"$version\" \"CI build under Ambient.\"\ndch -r ''\n\n"]
           cwd=/workspace/src (exists? true)
           extra_env=[]
    + export DEBEMAIL=liw@liw.fi
    + DEBEMAIL=liw@liw.fi
    + export 'DEBFULLNAME=Lars Wirzenius'
    + DEBFULLNAME='Lars Wirzenius'
    + export HOME=/root
    + HOME=/root
    + export PATH=/root/.cargo/bin:/bin:/sbin
    + PATH=/root/.cargo/bin:/bin:/sbin
    + export SOPASS_LOG=trace
    + SOPASS_LOG=trace
    + export CARGO_TARGET_DIR=/workspace/cache
    + CARGO_TARGET_DIR=/workspace/cache
    + export CARGO_HOME=/workspace/deps
    + CARGO_HOME=/workspace/deps
    + make 'OFFLINE=--offline --locked'
    subplot docgen sopass.subplot -o sopass.html
     INFO Starting Subplot
     INFO Subplot finished successfully
    cargo clippy --offline --locked --all-targets -- -Dwarnings
        Checking sopass v0.5.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.83s
    cargo build --offline --locked --all-targets
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.09s
    cargo test --offline --locked --all-targets
        Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
         Running unittests src/lib.rs (/workspace/cache/debug/deps/sopass-c8927301952d39d6)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
         Running unittests src/main.rs (/workspace/cache/debug/deps/sopass-782358d950ba1deb)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
    cargo test --offline --locked --doc
        Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
       Doc-tests sopass
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
    cargo doc --offline --locked --no-deps
     Documenting sopass v0.5.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.92s
       Generated /workspace/cache/doc/sopass/index.html
    rm -f test.py test.log
    subplot codegen sopass.subplot -o test.py
     INFO Starting Subplot
     INFO Subplot finished successfully
    SOPASS_LOG=trace python3 test.py --log test.log
    srcdir /workspace/src
    datadir /tmp/tmpxjcbcn6s
    scenario: Showing value that does not exist fails
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file my.key
      step: when I run sopass --store xyzzy init --name primary --key my.key
      step: when I try to run sopass --store xyzzy value show foo
      step: then command fails
      step: then stderr contains "foo"
      step: then stdout is exactly ""
    scenario: Manages certificates
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file my.key
      step: given file other.key
      step: given file other.cert
      step: when I run sopass --store xyzzy init --name primary --key my.key
      step: when I run sopass --store xyzzy cert list
      step: then stdout is exactly "primary\n"
      step: when I run sopass --store xyzzy cert add --name secondary --cert other.cert
      step: when I run sopass --store xyzzy cert list
      step: then stdout contains "primary"
      step: then stdout contains "secondary"
      step: when I run mv other.key xyzzy/default.key
      step: when I run rm my.key
      step: when I run sopass --store xyzzy cert list
      step: then stdout contains "primary"
      step: then stdout contains "secondary"
      step: when I run sopass --store xyzzy cert remove primary
      step: when I run sopass --store xyzzy cert list
      step: then stdout doesn't contain "primary"
      step: then stdout contains "secondary"
      step: when I try to run sopass --store xyzzy cert remove secondary
      step: then command fails
      step: then stderr contains "secondary"
      step: when I run sopass --store xyzzy cert list
      step: then stdout contains "secondary"
    scenario: Initializes the password store
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file my.key
      step: then directory xyzzy does not exist
      step: when I run sopass --store xyzzy init --name primary --key my.key
      step: then file xyzzy/values.sopass exists
    scenario: Manages values
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file my.key
      step: when I run sopass --store xyzzy init --name primary --key my.key
      step: when I run sopass --store xyzzy value list
      step: then stdout is exactly ""
      step: given file value.dat
      step: when I run sopass --store xyzzy value add foo --file value.dat
      step: when I run sopass --store xyzzy value list
      step: then stdout is exactly "foo\n"
      step: when I run sopass --store xyzzy value show foo
      step: then stdout is exactly "bar\n"
      step: when I run sopass --store xyzzy value remove foo
      step: then stdout is exactly ""
      step: given file add-stdin.sh
      step: when I run sh add-stdin.sh
      step: when I run sopass --store xyzzy value list
      step: then stdout is exactly "foo\n"
      step: when I run sopass --store xyzzy value show foo
      step: then stdout is exactly "bar\n"
      step: when I run sopass --store xyzzy value remove foo
      step: then stdout is exactly ""
    scenario: Loads default configuration file
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file .config/sopass/sopass.yml from default.yaml
      step: when I run env HOME=. sopass config
      step: then stdout contains ""store": "/over/the/rainbow""
    scenario: Renames values
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file my.key
      step: when I run sopass --store xyzzy init --name primary --key my.key
      step: given file value.dat
      step: when I run sopass --store xyzzy value add foo --file value.dat
      step: when I run sopass --store xyzzy value add foobar --file value.dat
      step: when I try to run sopass --store xyzzy value rename ghost yo
      step: then command fails
      step: then stderr contains "ghost"
      step: when I try to run sopass --store xyzzy value rename foo foobar
      step: then command fails
      step: then stderr contains "foobar"
      step: when I run sopass --store xyzzy value rename foo yo
      step: when I run sopass --store xyzzy value list
      step: then stdout is exactly "foobar\nyo\n"
    scenario: Reports its version
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: when I run sopass --version
      step: then stdout matches regex ^sopass \\d+\\.\\d+\\.\\d+$
      step: when I run sopass version
      step: then stdout matches regex ^sopass \\d+\\.\\d+\\.\\d+$
    scenario: Imports from a file
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file my.key
      step: given file value.dat
      step: given file yo.dat
      step: when I run sopass --store xyzzy init --name primary --key my.key
      step: when I run sopass --store xyzzy value add foo --file value.dat
      step: when I run sopass --store xyzzy value add bar --file value.dat
      step: when I run sopass --store xyzzy value add yo --file value.dat
      step: when I run sopass --store xyzzy export store.json
      step: when I run sopass --store new init --name primary --key my.key
      step: when I run sopass --store new value add foo --file yo.dat
      step: when I run sopass --store new value add bar --file yo.dat
      step: when I run sopass --store new import store.json
      step: when I run sopass --store new value list
      step: then stdout contains "foo"
      step: then stdout contains "bar"
      step: then stdout contains "yo"
      step: when I run sopass --store new value show foo
      step: then stdout is exactly "bar\n"
      step: when I run sopass --store new value show bar
      step: then stdout is exactly "bar\n"
      step: when I run sopass --store new value show yo
      step: then stdout is exactly "bar\n"
    scenario: Reports a default configuration
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: when I run sopass config
      step: then stdout contains ""store": "/"
      step: then stdout contains ""sop": ""
      step: then stdout contains ""key_file": ""
      step: then file .config/sopass/sopass.yml does not exist
    scenario: Rejects unknown field in configuration file
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file unknown.yaml
      step: when I try to run sopass --config unknown.yaml config
      step: then command fails
      step: then stderr contains "unknown"
    scenario: Exports store to file
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: given file my.key
      step: when I run sopass --store xyzzy init --name primary --key my.key
      step: given file value.dat
      step: when I run sopass --store xyzzy value add foo --file value.dat
      step: when I run sopass --store xyzzy export store.json
      step: when I run cat store.json
      step: then file store.json contains ""certs":"
      step: then file store.json contains ""primary":"
      step: then file store.json contains ""kv":"
      step: then file store.json contains ""foo":"
    scenario: Loads specified configuration file
      step: given an installed sopass
    bindir /workspace/cache/debug
      step: when I try to run sopass --config custom.yaml config
      step: then command fails
      step: then stderr contains "custom.yaml"
      step: given file custom.yaml
      step: when I run sopass --config custom.yaml config
      step: then stdout contains ""sop": "soppy""
    OK, all scenarios finished successfully
    rm -f test.py test.log
    + git clean -fdx
    Removing sopass.html
    ++ dpkg-parsechangelog -SVersion
    ++ sed 's/-[^-]*$//'
    + V=0.5.0
    ++ date -u +%Y%m%dT%H%M%S
    + T=20250816T052331
    + version=0.5.0.ci20250816T052331-1
    + dch -v 0.5.0.ci20250816T052331-1 'CI build under Ambient.'
    + dch -r ''
    RUN: Action finished OK
    RUN: Action Deb
    [2025-08-16T05:23:32Z DEBUG ambient_ci::action] Plan::execute: Deb
    SPAWN: argv=["/bin/bash", "-c", "#!/bin/bash\nset -xeuo pipefail\n\necho \"PATH at start: $PATH\"\nexport PATH=\"/root/.cargo/bin:$PATH\"\nexport CARGO_HOME=/workspace/deps\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\n/bin/env\n\ncommand -v cargo\ncommand -v rustc\n\ncargo --version\nrustc --version\n\n# Get name and version of source package.\nname=\"$(dpkg-parsechangelog -SSource)\"\nversion=\"$(dpkg-parsechangelog -SVersion)\"\n\n# Get upstream version: everything before the last dash.\nuv=\"$(echo \"$version\" | sed 's/-[^-]*$//')\"\n\n# Files that will be created.\narch=\"$(dpkg --print-architecture)\"\norig=\"../${name}_${uv}.orig.tar.xz\"\ndeb=\"../${name}_${version}_${arch}.deb\"\nchanges=\"../${name}_${version}_${arch}.changes\"\n\n# Create \"upstream tarball\".\ngit archive HEAD | xz >\"$orig\"\n\n# Build package.\ndpkg-buildpackage -us -uc\n\n# Dump some information to make it easier to visually verify\n# everything looks OK. Also, test the package with the lintian tool.\n\nls -l ..\nfor x in ../*.deb; do dpkg -c \"$x\"; done\n# FIXME: disabled while this prevents radicle-native-ci deb from being built.\n# lintian -i --allow-root --fail-on warning ../*.changes\n\n# Move files to artifacts directory.\nmv ../*_* /workspace/artifacts\n        "]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
    + echo 'PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
    PATH at start: /root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + export PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    + export CARGO_HOME=/workspace/deps
    + CARGO_HOME=/workspace/deps
    + export DEBEMAIL=liw@liw.fi
    + DEBEMAIL=liw@liw.fi
    + export 'DEBFULLNAME=Lars Wirzenius'
    + DEBFULLNAME='Lars Wirzenius'
    + /bin/env
    DEBFULLNAME=Lars Wirzenius
    CARGO_TARGET_DIR=/workspace/cache
    PWD=/workspace/src
    SYSTEMD_EXEC_PID=292
    LANG=C.UTF-8
    CARGO_HOME=/workspace/deps
    DEBEMAIL=liw@liw.fi
    INVOCATION_ID=12c921f211bf486a84fd5a0c9c43d663
    SHLVL=2
    JOURNAL_STREAM=8:12707
    PATH=/root/.cargo/bin:/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
    OLDPWD=/
    _=/bin/env
    + command -v cargo
    /root/.cargo/bin/cargo
    + command -v rustc
    /root/.cargo/bin/rustc
    + cargo --version
    cargo 1.89.0 (c24e10642 2025-06-23)
    + rustc --version
    rustc 1.89.0 (29483883e 2025-08-04)
    ++ dpkg-parsechangelog -SSource
    + name=sopass
    ++ dpkg-parsechangelog -SVersion
    + version=0.5.0.ci20250816T052331-1
    ++ sed 's/-[^-]*$//'
    ++ echo 0.5.0.ci20250816T052331-1
    + uv=0.5.0.ci20250816T052331
    ++ dpkg --print-architecture
    + arch=amd64
    + orig=../sopass_0.5.0.ci20250816T052331.orig.tar.xz
    + deb=../sopass_0.5.0.ci20250816T052331-1_amd64.deb
    + changes=../sopass_0.5.0.ci20250816T052331-1_amd64.changes
    + git archive HEAD
    + xz
    + dpkg-buildpackage -us -uc
    dpkg-buildpackage: info: source package sopass
    dpkg-buildpackage: info: source version 0.5.0.ci20250816T052331-1
    dpkg-buildpackage: info: source distribution unstable
    dpkg-buildpackage: info: source changed by Lars Wirzenius <liw@liw.fi>
    dpkg-buildpackage: info: host architecture amd64
     dpkg-source --before-build .
     debian/rules clean
    dh clean
       dh_auto_clean
       dh_clean
     dpkg-source -b .
    dpkg-source: info: using source format '3.0 (quilt)'
    dpkg-source: info: building sopass using existing ./sopass_0.5.0.ci20250816T052331.orig.tar.xz
    dpkg-source: info: building sopass in sopass_0.5.0.ci20250816T052331-1.debian.tar.xz
    dpkg-source: info: building sopass in sopass_0.5.0.ci20250816T052331-1.dsc
     debian/rules build
    dh build
       dh_update_autotools_config
       dh_autoreconf
       dh_auto_configure
       debian/rules override_dh_auto_build
    make[1]: Entering directory '/workspace/src'
    true
    make[1]: Leaving directory '/workspace/src'
       debian/rules override_dh_auto_test
    make[1]: Entering directory '/workspace/src'
    echo tests are disabled, for now
    tests are disabled, for now
    make[1]: Leaving directory '/workspace/src'
       create-stamp debian/debhelper-build-stamp
     debian/rules binary
    dh binary
       dh_testroot
       dh_prep
       debian/rules override_dh_auto_install
    make[1]: Entering directory '/workspace/src'
    cargo install --offline --locked --target x86_64-unknown-linux-musl --path=. --root=debian/sopass/usr
      Installing sopass v0.5.0 (/workspace/src)
       Compiling proc-macro2 v1.0.92
       Compiling unicode-ident v1.0.14
       Compiling libc v0.2.169
       Compiling memchr v2.7.4
       Compiling bitflags v2.6.0
       Compiling serde v1.0.219
       Compiling quote v1.0.38
       Compiling hashbrown v0.15.2
       Compiling equivalent v1.0.1
       Compiling syn v2.0.92
       Compiling ryu v1.0.18
       Compiling itoa v1.0.14
       Compiling rustix v0.38.42
       Compiling anyhow v1.0.98
       Compiling utf8parse v0.2.2
       Compiling anstyle-parse v0.2.6
       Compiling indexmap v2.7.1
       Compiling aho-corasick v1.1.3
       Compiling getrandom v0.3.3
       Compiling rustix v1.0.7
       Compiling colorchoice v1.0.3
       Compiling is_terminal_polyfill v1.70.1
       Compiling cfg-if v1.0.0
       Compiling linux-raw-sys v0.4.14
       Compiling version_check v0.9.5
       Compiling anstyle-query v1.1.2
       Compiling anstyle v1.0.10
       Compiling regex-syntax v0.8.5
       Compiling serde_yml v0.0.12
       Compiling anstream v0.6.18
       Compiling serde_derive v1.0.219
       Compiling regex-automata v0.4.9
       Compiling thiserror v1.0.69
       Compiling serde_json v1.0.140
       Compiling option-ext v0.2.0
       Compiling linux-raw-sys v0.9.4
       Compiling thiserror v2.0.12
       Compiling regex v1.11.1
       Compiling dirs-sys v0.4.1
       Compiling libyml v0.0.5
       Compiling terminal_size v0.4.1
       Compiling thiserror-impl v2.0.12
       Compiling thiserror-impl v1.0.69
       Compiling unsafe-libyaml v0.2.11
       Compiling once_cell v1.20.2
       Compiling heck v0.5.0
       Compiling log v0.4.22
       Compiling clap_lex v0.7.4
       Compiling fastrand v2.3.0
       Compiling strsim v0.11.1
       Compiling tempfile v3.20.0
       Compiling clap_builder v4.5.23
       Compiling serde_yaml v0.9.34+deprecated
       Compiling env_filter v0.1.3
       Compiling clap_derive v4.5.18
       Compiling directories v5.0.1
       Compiling dirs-sys-next v0.1.2
       Compiling humantime v2.1.0
       Compiling env_logger v0.11.6
       Compiling directories-next v2.0.0
       Compiling clingwrap v0.2.0
       Compiling confy v0.6.1
       Compiling clap v4.5.23
       Compiling sopass v0.5.0 (/workspace/src)
        Finished `release` profile [optimized] target(s) in 37.03s
      Installing debian/sopass/usr/bin/sopass
       Installed package `sopass v0.5.0 (/workspace/src)` (executable `sopass`)
    warning: be sure to add `debian/sopass/usr/bin` to your PATH to be able to run the installed binaries
    find debian -name ".crates*" -delete
    make[1]: Leaving directory '/workspace/src'
       dh_installdocs
       dh_installchangelogs
       dh_lintian
       dh_perl
       dh_link
       dh_strip_nondeterminism
       dh_compress
       dh_fixperms
       dh_missing
       dh_strip
       dh_makeshlibs
       dh_shlibdeps
    dpkg-shlibdeps: warning: couldn't parse dynamic symbol definition: no symbols
       dh_installdeb
       dh_gencontrol
    dpkg-gencontrol: warning: Depends field of package sopass: substitution variable ${shlibs:Depends} used, but is not defined
    dpkg-gencontrol: warning: Depends field of package sopass: substitution variable ${shlibs:Depends} used, but is not defined
       dh_md5sums
       dh_builddeb
    dpkg-deb: building package 'sopass-dbgsym' in '../sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb'.
    dpkg-deb: building package 'sopass' in '../sopass_0.5.0.ci20250816T052331-1_amd64.deb'.
     dpkg-genbuildinfo -O../sopass_0.5.0.ci20250816T052331-1_amd64.buildinfo
     dpkg-genchanges -O../sopass_0.5.0.ci20250816T052331-1_amd64.changes
    dpkg-genchanges: info: including full source code in upload
     dpkg-source --after-build .
    dpkg-buildpackage: info: full upload (original source is included)
    + ls -l ..
    total 1084
    drwxr-xr-x 2 root root   4096 Aug 16 05:23 artifacts
    drwxr-xr-x 6 root root   4096 Aug 16 05:23 cache
    drwxr-xr-x 3 root root   4096 Aug 16 05:22 deps
    -rw-r--r-- 1 root root 122624 Aug 16 05:24 sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb
    -rw-r--r-- 1 root root   2292 Aug 16 05:23 sopass_0.5.0.ci20250816T052331-1.debian.tar.xz
    -rw-r--r-- 1 root root    926 Aug 16 05:23 sopass_0.5.0.ci20250816T052331-1.dsc
    -rw-r--r-- 1 root root   5756 Aug 16 05:24 sopass_0.5.0.ci20250816T052331-1_amd64.buildinfo
    -rw-r--r-- 1 root root   2331 Aug 16 05:24 sopass_0.5.0.ci20250816T052331-1_amd64.changes
    -rw-r--r-- 1 root root 917684 Aug 16 05:24 sopass_0.5.0.ci20250816T052331-1_amd64.deb
    -rw-r--r-- 1 root root  25840 Aug 16 05:23 sopass_0.5.0.ci20250816T052331.orig.tar.xz
    drwxr-xr-x 6 root root   4096 Aug 16 05:23 src
    + for x in ../*.deb
    + dpkg -c ../sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/lib/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/lib/debug/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/lib/debug/.build-id/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/lib/debug/.build-id/eb/
    -rw-r--r-- root/root    628048 2025-08-16 05:23 ./usr/lib/debug/.build-id/eb/cb6b3ac7fc89a39250a96a7aaf22f6a2c10a19.debug
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/doc/
    lrwxrwxrwx root/root         0 2025-08-16 05:23 ./usr/share/doc/sopass-dbgsym -> sopass
    + for x in ../*.deb
    + dpkg -c ../sopass_0.5.0.ci20250816T052331-1_amd64.deb
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/bin/
    -rwxr-xr-x root/root   2847392 2025-08-16 05:23 ./usr/bin/sopass
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/doc/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/doc/sopass/
    -rw-r--r-- root/root       276 2025-08-16 05:23 ./usr/share/doc/sopass/changelog.Debian.gz
    -rw-r--r-- root/root      1150 2025-08-16 05:22 ./usr/share/doc/sopass/copyright
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/lintian/
    drwxr-xr-x root/root         0 2025-08-16 05:23 ./usr/share/lintian/overrides/
    -rw-r--r-- root/root       173 2025-08-16 05:22 ./usr/share/lintian/overrides/sopass
    + mv ../sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb ../sopass_0.5.0.ci20250816T052331-1.debian.tar.xz ../sopass_0.5.0.ci20250816T052331-1.dsc ../sopass_0.5.0.ci20250816T052331-1_amd64.buildinfo ../sopass_0.5.0.ci20250816T052331-1_amd64.changes ../sopass_0.5.0.ci20250816T052331-1_amd64.deb ../sopass_0.5.0.ci20250816T052331.orig.tar.xz /workspace/artifacts
    RUN: Action finished OK
    RUN: Action TarCreate {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-08-16T05:24:12Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vde",
            ),
            root: Some(
                "/workspace/cache",
            ),
            size: None,
        }
    [2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
    [2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] create archive file /dev/vde
    [2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
    [2025-08-16T05:24:12Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
    [2025-08-16T05:24:13Z 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:24:13Z DEBUG ambient_ci::action] Plan::execute: TarCreate {
            archive: "/dev/vdd",
            directory: "/workspace/artifacts",
        }
    [2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vdd",
            ),
            root: Some(
                "/workspace/artifacts",
            ),
            size: None,
        }
    [2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
    [2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
    [2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
    [2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
    [2025-08-16T05:24:13Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
    RUN: Action finished OK
    ambient-execute-plan ends
    EXIT CODE: 0
    
    ====================
[2025-08-16T05:24:17Z DEBUG ambient_ci::qemu] QEMU exit code 0
[2025-08-16T05:24:17Z DEBUG ambient_ci::run] remove old cache
[2025-08-16T05:24:17Z DEBUG ambient_ci::run] extract cache
[2025-08-16T05:24:18Z DEBUG ambient_ci::run] Executing post-plan steps
[2025-08-16T05:24:18Z DEBUG ambient_ci::action] Plan::execute: Dput {
        artifactsdir: "/srv/ambient-state/sopass/artifacts",
        dput_target: Some(
            "apt.liw.fi",
        ),
    }
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts/sopass_0.5.0.ci20250816T052331-1.dsc
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts/sopass_0.5.0.ci20250816T052331-1_amd64.buildinfo
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts/sopass-dbgsym_0.5.0.ci20250816T052331-1_amd64.deb
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts/sopass_0.5.0.ci20250816T052331-1_amd64.deb
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts/sopass_0.5.0.ci20250816T052331.orig.tar.xz
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts/sopass.html
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts/sopass_0.5.0.ci20250816T052331-1.debian.tar.xz
[2025-08-16T05:24:18Z DEBUG ambient_ci::util] found /srv/ambient-state/sopass/artifacts/sopass_0.5.0.ci20250816T052331-1_amd64.changes
[2025-08-16T05:24:18Z INFO  ambient_ci::util] dput apt.liw.fi /srv/ambient-state/sopass/artifacts/sopass_0.5.0.ci20250816T052331-1_amd64.changes
[2025-08-16T05:24:19Z DEBUG ambient_ci::action] Plan::execute: Rsync {
        artifactsdir: "/srv/ambient-state/sopass/artifacts",
        rsync_target: Some(
            "ci@http.liw.fi:/srv/http/doc.liw.fi/sopass",
        ),
    }
[2025-08-16T05:24:19Z INFO  ambient_ci::util] rsync /srv/ambient-state/sopass/artifacts/. -> ci@http.liw.fi:/srv/http/doc.liw.fi/sopass/.
[2025-08-16T05:24:19Z DEBUG ambient_ci::project] write project state to /srv/ambient-state/sopass/meta.yaml
[2025-08-16T05:24:20Z INFO  ambient] ambient ends successfully

Ambient stderr

<empty log>