CI run for rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M

Radicle repository id rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M

Table of Contents

Run log

[=3h[=3hBdsDxe: failed to load Boot0001 "UEFI QEMU DVD-ROM QM00005 " from PciRoot(0x0)/Pci(0x1F,0x2)/Sata(0x2,0xFFFF,0x0): Not Found
BdsDxe: loading Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0)
BdsDxe: starting Boot0002 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x2,0x0)
Welcome to GRUB!


INFO: Block devices
NAME   MAJ:MIN RM   SIZE RO TYPE MOUNTPOINTS
vda    254:0    0   100G  0 disk 
├─vda1 254:1    0   953M  0 part 
└─vda2 254:2    0  99.1G  0 part /
vdb    254:16   0   6.1M  1 disk 
vdc    254:32   0   830K  1 disk 
vdd    254:48   0 953.7M  0 disk 
vde    254:64   0  27.9G  0 disk 
vdf    254:80   0 328.3M  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-12-20 14:10 ./
-rw-r--r-- _rad/_rad       777 2025-12-20 14:10 ./plan.yaml
-rwxr-xr-x _rad/_rad   6394112 2025-12-20 14:10 ./run-ci
INFO: Extracted files:
  5636108      4 drwxr-xr-x   2 1000     1000         4096 Dec 20 14:10 .
  5636110   6248 -rwxr-xr-x   1 1000     1000      6394112 Dec 20 14:10 ./run-ci
  5636109      4 -rw-r--r--   1 1000     1000          777 Dec 20 14:10 ./plan.yaml
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-12-20T14:10:50Z TRACE ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 starts
[2025-12-20T14:10:50Z DEBUG ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 starts
[2025-12-20T14:10:50Z INFO  ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 starts
[2025-12-20T14:10:50Z WARN  ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 starts
[2025-12-20T14:10:50Z ERROR ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 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: cargo_fmt
- action: cargo_clippy
- action: cargo_test
- action: tar_create
  archive: /dev/vde
  directory: /workspace/cache
- action: tar_create
  archive: /dev/vdd
  directory: /workspace/artifacts
executor_drive: /dev/vdb
source_drive: /dev/vdc
artifact_drive: /dev/vdd
cache_drive: /dev/vde
deps_drive: /dev/vdf
workspace_dir: /workspace
source_dir: /workspace/src
deps_dir: /workspace/deps
cache_dir: /workspace/cache
artifacts_dir: /workspace/artifacts


[2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action Mkdir(
        Mkdir {
            pathname: "/workspace",
        },
    )
[2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: self=Mkdir(
        Mkdir {
            pathname: "/workspace",
        },
    )
[2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
[2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action Mkdir(
        Mkdir {
            pathname: "/workspace/artifacts",
        },
    )
[2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: self=Mkdir(
        Mkdir {
            pathname: "/workspace/artifacts",
        },
    )
[2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
[2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action TarExtract(
        TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        },
    )
[2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarExtract(
        TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        },
    )
[2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
[2025-12-20T14:10:50Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-12-20T14:10:50Z TRACE ambient_ci::vdrive] extraction OK
[2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action TarExtract(
        TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        },
    )
[2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarExtract(
        TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        },
    )
[2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
[2025-12-20T14:10:50Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-12-20T14:10:53Z TRACE ambient_ci::vdrive] extraction OK
[2025-12-20T14:10:53Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:10:53Z DEBUG ambient_ci::plan] RUN: Action TarExtract(
        TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        },
    )
[2025-12-20T14:10:53Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarExtract(
        TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        },
    )
[2025-12-20T14:10:53Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
[2025-12-20T14:10:53Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-12-20T14:11:19Z TRACE ambient_ci::vdrive] extraction OK
[2025-12-20T14:11:19Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:11:19Z DEBUG ambient_ci::plan] RUN: Action CargoFmt(
        CargoFmt,
    )
[2025-12-20T14:11:19Z DEBUG ambient_ci::action] RunnableAction::execute: self=CargoFmt(
        CargoFmt,
    )
[2025-12-20T14:11:19Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
SPAWN: argv=["cargo", "--version"]
       cwd=/workspace/src (exists? true)
cargo 1.92.0 (344c4567c 2025-10-21)
SPAWN: argv=["cargo", "clippy", "--version"]
       cwd=/workspace/src (exists? true)
clippy 0.1.92 (ded5c06cf2 2025-12-08)
SPAWN: argv=["rustc", "--version"]
       cwd=/workspace/src (exists? true)
rustc 1.92.0 (ded5c06cf 2025-12-08)
SPAWN: argv=["cargo", "fmt", "--check"]
       cwd=/workspace/src (exists? true)
[2025-12-20T14:11:20Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:11:20Z DEBUG ambient_ci::plan] RUN: Action CargoClippy(
        CargoClippy,
    )
[2025-12-20T14:11:20Z DEBUG ambient_ci::action] RunnableAction::execute: self=CargoClippy(
        CargoClippy,
    )
[2025-12-20T14:11:20Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
SPAWN: argv=["cargo", "--version"]
       cwd=/workspace/src (exists? true)
cargo 1.92.0 (344c4567c 2025-10-21)
SPAWN: argv=["cargo", "clippy", "--version"]
       cwd=/workspace/src (exists? true)
clippy 0.1.92 (ded5c06cf2 2025-12-08)
SPAWN: argv=["rustc", "--version"]
       cwd=/workspace/src (exists? true)
rustc 1.92.0 (ded5c06cf 2025-12-08)
SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
       cwd=/workspace/src (exists? true)
   Compiling proc-macro2 v1.0.95
   Compiling unicode-ident v1.0.18
   Compiling version_check v0.9.5
   Compiling libc v0.2.172
   Compiling typenum v1.18.0
   Compiling generic-array v0.14.7
   Compiling quote v1.0.40
   Compiling syn v2.0.101
    Checking crypto-common v0.1.6
    Checking cfg-if v1.0.0
    Checking subtle v2.6.1
   Compiling serde v1.0.219
    Checking stable_deref_trait v1.2.0
   Compiling jobserver v0.1.33
   Compiling shlex v1.3.0
   Compiling cc v1.2.22
   Compiling synstructure v0.13.2
   Compiling serde_derive v1.0.219
   Compiling zerofrom-derive v0.1.6
    Checking zerofrom v0.1.6
   Compiling yoke-derive v0.8.0
    Checking yoke v0.8.0
   Compiling zerovec-derive v0.11.1
   Compiling syn v1.0.109
   Compiling pkg-config v0.3.32
    Checking zerovec v0.11.2
   Compiling displaydoc v0.2.5
    Checking block-buffer v0.10.4
    Checking block-padding v0.3.3
    Checking cpufeatures v0.2.17
    Checking inout v0.1.4
    Checking digest v0.10.7
    Checking cipher v0.4.4
    Checking tinystr v0.8.1
    Checking getrandom v0.2.16
    Checking litemap v0.8.0
    Checking writeable v0.6.1
   Compiling thiserror v1.0.69
    Checking icu_locale_core v2.0.0
    Checking zerotrie v0.2.2
    Checking potential_utf v0.1.2
   Compiling thiserror-impl v1.0.69
   Compiling icu_properties_data v2.0.0
   Compiling icu_normalizer_data v2.0.0
    Checking icu_provider v2.0.0
    Checking icu_collections v2.0.0
    Checking bitflags v2.9.0
   Compiling vcpkg v0.2.15
   Compiling amplify_syn v2.0.1
   Compiling libz-sys v1.1.22
    Checking universal-hash v0.5.1
   Compiling proc-macro-error-attr v1.0.4
    Checking signature v1.6.4
    Checking opaque-debug v0.3.1
    Checking smallvec v1.15.0
   Compiling amplify_derive v4.0.1
    Checking icu_normalizer v2.0.0
    Checking ed25519 v1.5.3
    Checking icu_properties v2.0.0
   Compiling proc-macro-error v1.0.4
    Checking amplify_num v0.5.3
    Checking ascii v1.1.0
    Checking ct-codecs v1.1.5
    Checking ec25519 v0.1.0
    Checking idna_adapter v1.2.1
    Checking polyval v0.6.2
    Checking amplify v4.8.1
    Checking sha2 v0.10.9
   Compiling sqlite3-src v0.5.1
   Compiling libgit2-sys v0.17.0+1.8.1
    Checking base64ct v1.7.3
    Checking keccak v0.1.5
    Checking utf8_iter v1.0.4
   Compiling rustix v1.0.7
    Checking percent-encoding v2.3.1
    Checking form_urlencoded v1.2.1
    Checking idna v1.0.3
    Checking sha3 v0.10.8
    Checking pem-rfc7468 v0.7.0
    Checking cyphergraphy v0.3.0
   Compiling git-ref-format-core v0.3.1
    Checking ghash v0.5.1
    Checking rand_core v0.6.4
    Checking ctr v0.9.2
    Checking aes v0.8.4
    Checking aead v0.5.2
    Checking base32 v0.4.0
    Checking byteorder v1.5.0
   Compiling data-encoding v2.9.0
    Checking linux-raw-sys v0.9.4
   Compiling data-encoding-macro-internal v0.1.16
    Checking blowfish v0.9.1
    Checking cypheraddr v0.4.0
    Checking aes-gcm v0.10.3
   Compiling git-ref-format-macro v0.3.1
    Checking ssh-encoding v0.2.0
    Checking url v2.5.4
    Checking poly1305 v0.8.0
    Checking cbc v0.1.2
    Checking chacha20 v0.9.1
    Checking pbkdf2 v0.12.2
   Compiling getrandom v0.3.3
    Checking log v0.4.27
    Checking fastrand v2.3.0
   Compiling typeid v1.0.3
    Checking zeroize v1.8.1
    Checking data-encoding-macro v0.1.18
    Checking bcrypt-pbkdf v0.10.0
    Checking ssh-cipher v0.2.0
    Checking git-ref-format v0.3.1
    Checking sqlite3-sys v0.15.2
    Checking socks5-client v0.4.1
    Checking rand v0.8.5
    Checking hashbrown v0.15.3
    Checking signature v2.2.0
   Compiling serde_json v1.0.140
    Checking utf8parse v0.2.2
    Checking base-x v0.2.11
    Checking equivalent v1.0.2
   Compiling crossbeam-utils v0.8.21
    Checking radicle-std-ext v0.1.1
    Checking indexmap v2.9.0
    Checking multibase v0.9.1
    Checking anstyle-parse v0.2.7
    Checking ssh-key v0.6.7
    Checking qcheck v1.0.0
    Checking cyphernet v0.5.2
    Checking sqlite v0.32.0
    Checking radicle-ssh v0.10.0
    Checking anstyle-query v1.1.3
    Checking anstyle v1.0.11
    Checking colorchoice v1.0.4
    Checking ryu v1.0.20
    Checking itoa v1.0.15
    Checking is_terminal_polyfill v1.70.1
    Checking tinyvec_macros v0.1.1
    Checking memchr v2.7.4
    Checking tinyvec v1.9.0
    Checking anstream v0.6.19
    Checking erased-serde v0.4.6
    Checking radicle-dag v0.10.0
    Checking terminal_size v0.4.2
    Checking nonempty v0.9.0
    Checking once_cell v1.21.3
    Checking strsim v0.11.1
   Compiling heck v0.5.0
   Compiling thiserror v2.0.12
    Checking clap_lex v0.7.5
    Checking clap_builder v4.5.41
   Compiling clap_derive v4.5.41
    Checking tempfile v3.20.0
    Checking crossbeam-channel v0.5.15
    Checking serde-untagged v0.1.7
    Checking unicode-normalization v0.1.24
    Checking localtime v1.3.1
    Checking bytesize v2.0.1
   Compiling thiserror-impl v2.0.12
    Checking base64 v0.21.7
    Checking dunce v1.0.5
    Checking fast-glob v0.3.3
    Checking siphasher v1.0.1
    Checking clap v4.5.41
    Checking uuid v1.16.0
    Checking nonempty v0.11.0
    Checking git2 v0.19.0
    Checking radicle-git-ext v0.8.1
    Checking radicle-crypto v0.14.0
    Checking radicle-cob v0.17.0
    Checking radicle v0.20.0
    Checking radicle-job v0.4.0 (/workspace/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 01s
[2025-12-20T14:13:22Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:13:22Z DEBUG ambient_ci::plan] RUN: Action CargoTest(
        CargoTest,
    )
[2025-12-20T14:13:22Z DEBUG ambient_ci::action] RunnableAction::execute: self=CargoTest(
        CargoTest,
    )
[2025-12-20T14:13:22Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
SPAWN: argv=["cargo", "--version"]
       cwd=/workspace/src (exists? true)
cargo 1.92.0 (344c4567c 2025-10-21)
SPAWN: argv=["cargo", "clippy", "--version"]
       cwd=/workspace/src (exists? true)
clippy 0.1.92 (ded5c06cf2 2025-12-08)
SPAWN: argv=["rustc", "--version"]
       cwd=/workspace/src (exists? true)
rustc 1.92.0 (ded5c06cf 2025-12-08)
SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
       cwd=/workspace/src (exists? true)
   Compiling typenum v1.18.0
   Compiling libc v0.2.172
   Compiling generic-array v0.14.7
   Compiling cfg-if v1.0.0
   Compiling subtle v2.6.1
   Compiling zerofrom v0.1.6
   Compiling stable_deref_trait v1.2.0
   Compiling yoke v0.8.0
   Compiling jobserver v0.1.33
   Compiling crypto-common v0.1.6
   Compiling serde v1.0.219
   Compiling cc v1.2.22
   Compiling zerovec v0.11.2
   Compiling block-padding v0.3.3
   Compiling block-buffer v0.10.4
   Compiling cpufeatures v0.2.17
   Compiling inout v0.1.4
   Compiling digest v0.10.7
   Compiling cipher v0.4.4
   Compiling tinystr v0.8.1
   Compiling getrandom v0.2.16
   Compiling litemap v0.8.0
   Compiling writeable v0.6.1
   Compiling zerotrie v0.2.2
   Compiling icu_locale_core v2.0.0
   Compiling potential_utf v0.1.2
   Compiling icu_collections v2.0.0
   Compiling icu_provider v2.0.0
   Compiling bitflags v2.9.0
   Compiling libz-sys v1.1.22
   Compiling thiserror v1.0.69
   Compiling icu_normalizer_data v2.0.0
   Compiling icu_properties_data v2.0.0
   Compiling universal-hash v0.5.1
   Compiling opaque-debug v0.3.1
   Compiling smallvec v1.15.0
   Compiling signature v1.6.4
   Compiling ed25519 v1.5.3
   Compiling icu_normalizer v2.0.0
   Compiling icu_properties v2.0.0
   Compiling ascii v1.1.0
   Compiling ct-codecs v1.1.5
   Compiling amplify_num v0.5.3
   Compiling ec25519 v0.1.0
   Compiling idna_adapter v1.2.1
   Compiling amplify v4.8.1
   Compiling polyval v0.6.2
   Compiling sha2 v0.10.9
   Compiling libgit2-sys v0.17.0+1.8.1
   Compiling sqlite3-src v0.5.1
   Compiling base64ct v1.7.3
   Compiling utf8_iter v1.0.4
   Compiling percent-encoding v2.3.1
   Compiling keccak v0.1.5
   Compiling pem-rfc7468 v0.7.0
   Compiling sha3 v0.10.8
   Compiling form_urlencoded v1.2.1
   Compiling idna v1.0.3
   Compiling ghash v0.5.1
   Compiling cyphergraphy v0.3.0
   Compiling git-ref-format-core v0.3.1
   Compiling rand_core v0.6.4
   Compiling aes v0.8.4
   Compiling ctr v0.9.2
   Compiling aead v0.5.2
   Compiling linux-raw-sys v0.9.4
   Compiling byteorder v1.5.0
   Compiling base32 v0.4.0
   Compiling cypheraddr v0.4.0
   Compiling blowfish v0.9.1
   Compiling rustix v1.0.7
   Compiling aes-gcm v0.10.3
   Compiling git-ref-format-macro v0.3.1
   Compiling url v2.5.4
   Compiling ssh-encoding v0.2.0
   Compiling poly1305 v0.8.0
   Compiling cbc v0.1.2
   Compiling chacha20 v0.9.1
   Compiling pbkdf2 v0.12.2
   Compiling log v0.4.27
   Compiling zeroize v1.8.1
   Compiling fastrand v2.3.0
   Compiling data-encoding v2.9.0
   Compiling data-encoding-macro v0.1.18
   Compiling bcrypt-pbkdf v0.10.0
   Compiling ssh-cipher v0.2.0
   Compiling git-ref-format v0.3.1
   Compiling sqlite3-sys v0.15.2
   Compiling socks5-client v0.4.1
   Compiling rand v0.8.5
   Compiling hashbrown v0.15.3
   Compiling radicle-std-ext v0.1.1
   Compiling signature v2.2.0
   Compiling utf8parse v0.2.2
   Compiling equivalent v1.0.2
   Compiling base-x v0.2.11
   Compiling multibase v0.9.1
   Compiling indexmap v2.9.0
   Compiling anstyle-parse v0.2.7
   Compiling ssh-key v0.6.7
   Compiling qcheck v1.0.0
   Compiling cyphernet v0.5.2
   Compiling sqlite v0.32.0
   Compiling getrandom v0.3.3
   Compiling typeid v1.0.3
   Compiling radicle-ssh v0.10.0
   Compiling memchr v2.7.4
   Compiling ryu v1.0.20
   Compiling tinyvec_macros v0.1.1
   Compiling anstyle-query v1.1.3
   Compiling is_terminal_polyfill v1.70.1
   Compiling anstyle v1.0.11
   Compiling colorchoice v1.0.4
   Compiling itoa v1.0.15
   Compiling serde_json v1.0.140
   Compiling anstream v0.6.19
   Compiling tinyvec v1.9.0
   Compiling erased-serde v0.4.6
   Compiling crossbeam-utils v0.8.21
   Compiling radicle-dag v0.10.0
   Compiling terminal_size v0.4.2
   Compiling nonempty v0.9.0
   Compiling strsim v0.11.1
   Compiling once_cell v1.21.3
   Compiling clap_lex v0.7.5
   Compiling clap_builder v4.5.41
   Compiling tempfile v3.20.0
   Compiling crossbeam-channel v0.5.15
   Compiling serde-untagged v0.1.7
   Compiling unicode-normalization v0.1.24
   Compiling git2 v0.19.0
   Compiling localtime v1.3.1
   Compiling bytesize v2.0.1
   Compiling dunce v1.0.5
   Compiling fast-glob v0.3.3
   Compiling siphasher v1.0.1
   Compiling base64 v0.21.7
   Compiling thiserror v2.0.12
   Compiling clap v4.5.41
   Compiling uuid v1.16.0
   Compiling nonempty v0.11.0
   Compiling radicle-git-ext v0.8.1
   Compiling radicle-crypto v0.14.0
   Compiling radicle-cob v0.17.0
   Compiling radicle v0.20.0
   Compiling radicle-job v0.4.0 (/workspace/src)
    Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 00s
     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_job-238247049fb1fadf)

running 4 tests
test test::idempotent_create ... ok
test test::missing_commit ... ok
test test::runs_insertion_order_iteration ... ok
test test::e2e ... ok

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

     Running unittests src/bin/rad-job.rs (/workspace/cache/debug/deps/rad_job-a28eea8ffeb7fb71)

running 0 tests

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

   Doc-tests radicle_job

running 1 test
test src/lib.rs - (line 13) ... ok

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

[2025-12-20T14:15:25Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:15:25Z DEBUG ambient_ci::plan] RUN: Action TarCreate(
        TarCreate {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        },
    )
[2025-12-20T14:15:25Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarCreate(
        TarCreate {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        },
    )
[2025-12-20T14:15:25Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
[2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vde",
        ),
        root: Some(
            "/workspace/cache",
        ),
        size: None,
    }
[2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
[2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] create archive file /dev/vde
[2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
[2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
[2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] calling tar_create
[2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] created virtual drive /dev/vde
[2025-12-20T14:15:36Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:15:36Z DEBUG ambient_ci::plan] RUN: Action TarCreate(
        TarCreate {
            archive: "/dev/vdd",
            directory: "/workspace/artifacts",
        },
    )
[2025-12-20T14:15:36Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarCreate(
        TarCreate {
            archive: "/dev/vdd",
            directory: "/workspace/artifacts",
        },
    )
[2025-12-20T14:15:36Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "HOME": "/root",
            "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",
        },
        source_dir: "/workspace/src",
        deps_dir: "/workspace/deps",
        artifacts_dir: "/workspace/artifacts",
    }
[2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
        filename: Some(
            "/dev/vdd",
        ),
        root: Some(
            "/workspace/artifacts",
        ),
        size: None,
    }
[2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
[2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
[2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
[2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
[2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] calling tar_create
[2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
[2025-12-20T14:15:36Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:15:36Z DEBUG ambient_ci::plan] All actions were performed successfully
ambient-execute-plan ends
EXIT CODE: 0

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "push",
  "repository": {
    "id": "rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M",
    "name": "radicle-job",
    "description": "Radicle Job Collaborative Object",
    "private": false,
    "default_branch": "main",
    "delegates": [
      "did:key:z6MkireRatUThvd3qzfKht1S44wpm4FEWSSa4PRMTSQZ3voM"
    ]
  },
  "pusher": {
    "id": "did:key:z6Mki2ago1X2A5UWgKpoSxyCRLLhyZMwpam4T3EKvRmFTyuy",
    "alias": "callisto.liw.fi"
  },
  "before": "525dc9a9e43217e038aa26a1dcb082ada32f1192",
  "after": "525dc9a9e43217e038aa26a1dcb082ada32f1192",
  "branch": "main",
  "commits": [
    "525dc9a9e43217e038aa26a1dcb082ada32f1192"
  ]
}

Ambient stdout

[2025-12-20T14:10:34Z INFO  ambient] ambient starts
[2025-12-20T14:10:34Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
deprecated: the `cpus` field is replaced by `qemu.cpus`
deprecated: the `memory` field is replaced by `qemu.memory`
[2025-12-20T14:10:34Z DEBUG ambient] complete configuration: Config {
        tmpdir: "/home/_rad/tmp",
        image_store: "/home/_rad/ambient-images",
        projects: "/dev/null",
        state: "/home/_rad/ambient-state",
        rsync_target: None,
        rsync_target_base: None,
        rsync_target_map: None,
        dput_target: None,
        executor: Some(
            "/usr/bin/ambient-execute-plan",
        ),
        artifacts_max_size: Byte(
            1000000000,
        ),
        cache_max_size: Byte(
            30000000000,
        ),
        qemu: QemuConfig {
            cpus: 2,
            memory: Byte(
                12000000000,
            ),
            kvm_binary: "/usr/bin/kvm",
            ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
            ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
        },
    }
[2025-12-20T14:10:34Z DEBUG ambient_ci::project] load project state from /home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/meta.yaml
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] latest commit: "525dc9a9e43217e038aa26a1dcb082ada32f1192"
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] is a git repository
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] git repository is clean
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] current (HEAD) commit: 525dc9a9e43217e038aa26a1dcb082ada32f1192
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] no dry run requested
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] forced run requested
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] run? true
[2025-12-20T14:10:34Z INFO  ambient_ci::run] project rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M: running CI
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-12-20T14:10:34Z DEBUG ambient_ci::plan] RUN: Action CargoFetch(
        CargoFetch,
    )
[2025-12-20T14:10:34Z DEBUG ambient_ci::action] RunnableAction::execute: self=CargoFetch(
        CargoFetch,
    )
[2025-12-20T14:10:34Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
        envs: {
            "CARGO_TARGET_DIR": "/home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/cache",
            "CARGO_HOME": "/home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/dependencies",
            "PATH": "/root/.cargo/bin:/bin:/home/_rad/.radicle/bin:/home/_rad/.cargo/bin",
        },
        source_dir: "/tmp/.tmpEiTffQ/src",
        deps_dir: "/home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/dependencies",
        artifacts_dir: "/home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/artifacts",
    }
SPAWN: argv=["cargo", "--version"]
       cwd=/tmp/.tmpEiTffQ/src (exists? true)
cargo 1.92.0 (344c4567c 2025-10-21)
SPAWN: argv=["cargo", "clippy", "--version"]
       cwd=/tmp/.tmpEiTffQ/src (exists? true)
clippy 0.1.92 (ded5c06cf2 2025-12-08)
SPAWN: argv=["rustc", "--version"]
       cwd=/tmp/.tmpEiTffQ/src (exists? true)
rustc 1.92.0 (ded5c06cf 2025-12-08)
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] copy_partial_tree: /tmp/.tmpEiTffQ/src => /tmp/.tmpEfVfe8
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/src wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/src/lib.rs wanted=true
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] copy_partial_tree: copy /tmp/.tmpEiTffQ/src/src/lib.rs => /tmp/.tmpEfVfe8/src/lib.rs
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/src/error.rs wanted=true
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] copy_partial_tree: copy /tmp/.tmpEiTffQ/src/src/error.rs => /tmp/.tmpEfVfe8/src/error.rs
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/src/display.rs wanted=true
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] copy_partial_tree: copy /tmp/.tmpEiTffQ/src/src/display.rs => /tmp/.tmpEfVfe8/src/display.rs
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/src/bin wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/src/bin/rad-job.rs wanted=true
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] copy_partial_tree: copy /tmp/.tmpEiTffQ/src/src/bin/rad-job.rs => /tmp/.tmpEfVfe8/src/bin/rad-job.rs
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/release.toml wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/flake.nix wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/flake.lock wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/README.md wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/Cargo.toml wanted=true
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] copy_partial_tree: copy /tmp/.tmpEiTffQ/src/Cargo.toml => /tmp/.tmpEfVfe8/Cargo.toml
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/Cargo.lock wanted=true
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] copy_partial_tree: copy /tmp/.tmpEiTffQ/src/Cargo.lock => /tmp/.tmpEfVfe8/Cargo.lock
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/CHANGELOG.md wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.radicle wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.radicle/ambient.yaml wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.gitignore wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.envrc wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/index wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/HEAD wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/config wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/logs wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/logs/HEAD wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/logs/refs wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/logs/refs/heads wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/logs/refs/heads/main wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/logs/refs/remotes wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/logs/refs/remotes/origin wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/logs/refs/remotes/origin/HEAD wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/packed-refs wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/refs wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/refs/remotes wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/refs/remotes/origin wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/refs/remotes/origin/HEAD wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/refs/tags wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/refs/heads wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/refs/heads/main wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/info wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/info/commit-graph wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/info/packs wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-b494d4ac67d3495cf33abaeec677dcbd04e387f3.pack wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-fc499c2952311d0a45a66178f7660f2adaa6b122.idx wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-6fd02466b4d54ff80d1b307cdb8b47439641ff6f.rev wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-fc499c2952311d0a45a66178f7660f2adaa6b122.pack wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-b494d4ac67d3495cf33abaeec677dcbd04e387f3.idx wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-2bf8325637bf04c384e52501a3b468cf18820d89.pack wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-6fd02466b4d54ff80d1b307cdb8b47439641ff6f.idx wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-d9b479429b0c0f3df44d8a9af109a3357d0127e1.pack wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-6fd02466b4d54ff80d1b307cdb8b47439641ff6f.bitmap wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-ea4d89fd713f6b32ebb75da4f681a573b0cf4d84.pack wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-2bf8325637bf04c384e52501a3b468cf18820d89.idx wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-92ca64db5e49253bd190bc9780fbf9cc479fb3ba.pack wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-92ca64db5e49253bd190bc9780fbf9cc479fb3ba.idx wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-6fd02466b4d54ff80d1b307cdb8b47439641ff6f.pack wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-d9b479429b0c0f3df44d8a9af109a3357d0127e1.idx wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/objects/pack/pack-ea4d89fd713f6b32ebb75da4f681a573b0cf4d84.idx wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/branches wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/update.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/commit-msg.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/applypatch-msg.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/pre-merge-commit.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/post-update.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/pre-push.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/pre-rebase.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/push-to-checkout.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/prepare-commit-msg.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/fsmonitor-watchman.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/pre-commit.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/pre-receive.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/sendemail-validate.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/hooks/pre-applypatch.sample wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/description wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/info wanted=false
[2025-12-20T14:10:34Z DEBUG ambient_ci::util] path=/tmp/.tmpEiTffQ/src/.git/info/exclude wanted=false
SPAWN: argv=["cargo", "fetch", "--locked"]
       cwd=/tmp/.tmpEfVfe8 (exists? true)
[2025-12-20T14:10:34Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-12-20T14:10:34Z DEBUG ambient_ci::plan] All actions were performed successfully
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] create virtual drive /home/_rad/tmp/.tmpE0KBRQ/src.tar
[2025-12-20T14:10:34Z DEBUG ambient_ci::run] create virtual drive /home/_rad/tmp/.tmpE0KBRQ/deps.tar
[2025-12-20T14:10:37Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-12-20T14:10:37Z DEBUG ambient_ci::run] create_executor_vdrive: plan=RunnablePlan {
        steps: [
            Mkdir(
                Mkdir {
                    pathname: "/workspace",
                },
            ),
            Mkdir(
                Mkdir {
                    pathname: "/workspace/artifacts",
                },
            ),
            TarExtract(
                TarExtract {
                    archive: "/dev/vdc",
                    directory: "/workspace/src",
                },
            ),
            TarExtract(
                TarExtract {
                    archive: "/dev/vdf",
                    directory: "/workspace/deps",
                },
            ),
            TarExtract(
                TarExtract {
                    archive: "/dev/vde",
                    directory: "/workspace/cache",
                },
            ),
            CargoFmt(
                CargoFmt,
            ),
            CargoClippy(
                CargoClippy,
            ),
            CargoTest(
                CargoTest,
            ),
            TarCreate(
                TarCreate {
                    archive: "/dev/vde",
                    directory: "/workspace/cache",
                },
            ),
            TarCreate(
                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-12-20T14:10:37Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-12-20T14:10:37Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /home/_rad/tmp/.tmpE0KBRQ/ambient-execute-plan/run-ci
[2025-12-20T14:10:37Z DEBUG ambient_ci::run] copy OK: true
[2025-12-20T14:10:37Z DEBUG ambient_ci::run] create virtual drive /home/_rad/tmp/.tmpE0KBRQ/executor.tar
[2025-12-20T14:10:44Z DEBUG ambient_ci::project] removing run log file /home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/run.log
[2025-12-20T14:10:44Z DEBUG ambient_ci::project] statedir is /home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M, exists? true
[2025-12-20T14:10:44Z DEBUG ambient_ci::project] creating run log file /home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/run.log
[2025-12-20T14:10:44Z DEBUG ambient_ci::project] created run log file /home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/run.log OK
[2025-12-20T14:10:44Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-12-20T14:10:44Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-12-20T14:10:44Z DEBUG ambient_ci::cloud_init] with_runcmd called: "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n"
[2025-12-20T14:10:44Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-12-20T14:10:44Z DEBUG ambient_ci::cloud_init] LocalDataStoreBuilder: LocalDataStoreBuilder {
        hostname: Some(
            "ambient",
        ),
        network: false,
        bootcmd: [],
        runcmd: [
            "echo xyzzy > /dev/ttyS1",
            "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n",
            "poweroff",
        ],
    }
[2025-12-20T14:10:44Z DEBUG ambient_ci::qemu] run QEMU
[2025-12-20T14:10:44Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-12-20T14:10:44Z DEBUG ambient_ci::qemu_utils] qemu-img create /home/_rad/tmp/.tmpcbo9up/.tmpQUg26H/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-12-20T14:10:44Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-12-20T14:10:44Z 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-12-20T14:10:44Z DEBUG ambient_ci::cloud_init] write /tmp/.tmphvUBiz/meta-data
[2025-12-20T14:10:44Z DEBUG ambient_ci::cloud_init] write /tmp/.tmphvUBiz/user-data
[2025-12-20T14:10:44Z DEBUG ambient_ci::qemu] set console log file to /home/_rad/tmp/.tmpcbo9up/.tmpQUg26H/console.log
[2025-12-20T14:10:44Z DEBUG ambient_ci::util] create file /home/_rad/tmp/.tmpcbo9up/.tmpQUg26H/console.log
[2025-12-20T14:10:44Z DEBUG ambient_ci::qemu] set run file to /home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/run.log
[2025-12-20T14:10:44Z DEBUG ambient_ci::util] create file /home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/run.log
[2025-12-20T14:10:44Z DEBUG ambient_ci::qemu] run QEMU: QemuArgs {
        args: [
            "-m",
            "11444",
            "-smp",
            "cpus=2",
            "-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:/home/_rad/tmp/.tmpcbo9up/.tmpQUg26H/console.log",
            "-serial",
            "file:/home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/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=/home/_rad/tmp/.tmpcbo9up/.tmpQUg26H/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/home/_rad/tmp/.tmpcbo9up/.tmpQUg26H/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpE0KBRQ/executor.tar,readonly=on",
            "-cdrom",
            "/home/_rad/tmp/.tmpcbo9up/.tmpQUg26H/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpE0KBRQ/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpE0KBRQ/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpE0KBRQ/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpE0KBRQ/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-12-20T14:15:39Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-12-20T14:15:39Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-12-20T14:10:50Z TRACE ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 starts
    [2025-12-20T14:10:50Z DEBUG ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 starts
    [2025-12-20T14:10:50Z INFO  ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 starts
    [2025-12-20T14:10:50Z WARN  ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 starts
    [2025-12-20T14:10:50Z ERROR ambient_execute_plan] ambient-execute-plan version 0.10.0@9492962 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: cargo_fmt
    - action: cargo_clippy
    - action: cargo_test
    - action: tar_create
      archive: /dev/vde
      directory: /workspace/cache
    - action: tar_create
      archive: /dev/vdd
      directory: /workspace/artifacts
    executor_drive: /dev/vdb
    source_drive: /dev/vdc
    artifact_drive: /dev/vdd
    cache_drive: /dev/vde
    deps_drive: /dev/vdf
    workspace_dir: /workspace
    source_dir: /workspace/src
    deps_dir: /workspace/deps
    cache_dir: /workspace/cache
    artifacts_dir: /workspace/artifacts
    
    
    [2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action Mkdir(
            Mkdir {
                pathname: "/workspace",
            },
        )
    [2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: self=Mkdir(
            Mkdir {
                pathname: "/workspace",
            },
        )
    [2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    [2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action Mkdir(
            Mkdir {
                pathname: "/workspace/artifacts",
            },
        )
    [2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: self=Mkdir(
            Mkdir {
                pathname: "/workspace/artifacts",
            },
        )
    [2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    [2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action TarExtract(
            TarExtract {
                archive: "/dev/vdc",
                directory: "/workspace/src",
            },
        )
    [2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarExtract(
            TarExtract {
                archive: "/dev/vdc",
                directory: "/workspace/src",
            },
        )
    [2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    [2025-12-20T14:10:50Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-12-20T14:10:50Z TRACE ambient_ci::vdrive] extraction OK
    [2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:10:50Z DEBUG ambient_ci::plan] RUN: Action TarExtract(
            TarExtract {
                archive: "/dev/vdf",
                directory: "/workspace/deps",
            },
        )
    [2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarExtract(
            TarExtract {
                archive: "/dev/vdf",
                directory: "/workspace/deps",
            },
        )
    [2025-12-20T14:10:50Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    [2025-12-20T14:10:50Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-12-20T14:10:53Z TRACE ambient_ci::vdrive] extraction OK
    [2025-12-20T14:10:53Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:10:53Z DEBUG ambient_ci::plan] RUN: Action TarExtract(
            TarExtract {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
        )
    [2025-12-20T14:10:53Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarExtract(
            TarExtract {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
        )
    [2025-12-20T14:10:53Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    [2025-12-20T14:10:53Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-12-20T14:11:19Z TRACE ambient_ci::vdrive] extraction OK
    [2025-12-20T14:11:19Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:11:19Z DEBUG ambient_ci::plan] RUN: Action CargoFmt(
            CargoFmt,
        )
    [2025-12-20T14:11:19Z DEBUG ambient_ci::action] RunnableAction::execute: self=CargoFmt(
            CargoFmt,
        )
    [2025-12-20T14:11:19Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    SPAWN: argv=["cargo", "--version"]
           cwd=/workspace/src (exists? true)
    cargo 1.92.0 (344c4567c 2025-10-21)
    SPAWN: argv=["cargo", "clippy", "--version"]
           cwd=/workspace/src (exists? true)
    clippy 0.1.92 (ded5c06cf2 2025-12-08)
    SPAWN: argv=["rustc", "--version"]
           cwd=/workspace/src (exists? true)
    rustc 1.92.0 (ded5c06cf 2025-12-08)
    SPAWN: argv=["cargo", "fmt", "--check"]
           cwd=/workspace/src (exists? true)
    [2025-12-20T14:11:20Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:11:20Z DEBUG ambient_ci::plan] RUN: Action CargoClippy(
            CargoClippy,
        )
    [2025-12-20T14:11:20Z DEBUG ambient_ci::action] RunnableAction::execute: self=CargoClippy(
            CargoClippy,
        )
    [2025-12-20T14:11:20Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    SPAWN: argv=["cargo", "--version"]
           cwd=/workspace/src (exists? true)
    cargo 1.92.0 (344c4567c 2025-10-21)
    SPAWN: argv=["cargo", "clippy", "--version"]
           cwd=/workspace/src (exists? true)
    clippy 0.1.92 (ded5c06cf2 2025-12-08)
    SPAWN: argv=["rustc", "--version"]
           cwd=/workspace/src (exists? true)
    rustc 1.92.0 (ded5c06cf 2025-12-08)
    SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
           cwd=/workspace/src (exists? true)
       Compiling proc-macro2 v1.0.95
       Compiling unicode-ident v1.0.18
       Compiling version_check v0.9.5
       Compiling libc v0.2.172
       Compiling typenum v1.18.0
       Compiling generic-array v0.14.7
       Compiling quote v1.0.40
       Compiling syn v2.0.101
        Checking crypto-common v0.1.6
        Checking cfg-if v1.0.0
        Checking subtle v2.6.1
       Compiling serde v1.0.219
        Checking stable_deref_trait v1.2.0
       Compiling jobserver v0.1.33
       Compiling shlex v1.3.0
       Compiling cc v1.2.22
       Compiling synstructure v0.13.2
       Compiling serde_derive v1.0.219
       Compiling zerofrom-derive v0.1.6
        Checking zerofrom v0.1.6
       Compiling yoke-derive v0.8.0
        Checking yoke v0.8.0
       Compiling zerovec-derive v0.11.1
       Compiling syn v1.0.109
       Compiling pkg-config v0.3.32
        Checking zerovec v0.11.2
       Compiling displaydoc v0.2.5
        Checking block-buffer v0.10.4
        Checking block-padding v0.3.3
        Checking cpufeatures v0.2.17
        Checking inout v0.1.4
        Checking digest v0.10.7
        Checking cipher v0.4.4
        Checking tinystr v0.8.1
        Checking getrandom v0.2.16
        Checking litemap v0.8.0
        Checking writeable v0.6.1
       Compiling thiserror v1.0.69
        Checking icu_locale_core v2.0.0
        Checking zerotrie v0.2.2
        Checking potential_utf v0.1.2
       Compiling thiserror-impl v1.0.69
       Compiling icu_properties_data v2.0.0
       Compiling icu_normalizer_data v2.0.0
        Checking icu_provider v2.0.0
        Checking icu_collections v2.0.0
        Checking bitflags v2.9.0
       Compiling vcpkg v0.2.15
       Compiling amplify_syn v2.0.1
       Compiling libz-sys v1.1.22
        Checking universal-hash v0.5.1
       Compiling proc-macro-error-attr v1.0.4
        Checking signature v1.6.4
        Checking opaque-debug v0.3.1
        Checking smallvec v1.15.0
       Compiling amplify_derive v4.0.1
        Checking icu_normalizer v2.0.0
        Checking ed25519 v1.5.3
        Checking icu_properties v2.0.0
       Compiling proc-macro-error v1.0.4
        Checking amplify_num v0.5.3
        Checking ascii v1.1.0
        Checking ct-codecs v1.1.5
        Checking ec25519 v0.1.0
        Checking idna_adapter v1.2.1
        Checking polyval v0.6.2
        Checking amplify v4.8.1
        Checking sha2 v0.10.9
       Compiling sqlite3-src v0.5.1
       Compiling libgit2-sys v0.17.0+1.8.1
        Checking base64ct v1.7.3
        Checking keccak v0.1.5
        Checking utf8_iter v1.0.4
       Compiling rustix v1.0.7
        Checking percent-encoding v2.3.1
        Checking form_urlencoded v1.2.1
        Checking idna v1.0.3
        Checking sha3 v0.10.8
        Checking pem-rfc7468 v0.7.0
        Checking cyphergraphy v0.3.0
       Compiling git-ref-format-core v0.3.1
        Checking ghash v0.5.1
        Checking rand_core v0.6.4
        Checking ctr v0.9.2
        Checking aes v0.8.4
        Checking aead v0.5.2
        Checking base32 v0.4.0
        Checking byteorder v1.5.0
       Compiling data-encoding v2.9.0
        Checking linux-raw-sys v0.9.4
       Compiling data-encoding-macro-internal v0.1.16
        Checking blowfish v0.9.1
        Checking cypheraddr v0.4.0
        Checking aes-gcm v0.10.3
       Compiling git-ref-format-macro v0.3.1
        Checking ssh-encoding v0.2.0
        Checking url v2.5.4
        Checking poly1305 v0.8.0
        Checking cbc v0.1.2
        Checking chacha20 v0.9.1
        Checking pbkdf2 v0.12.2
       Compiling getrandom v0.3.3
        Checking log v0.4.27
        Checking fastrand v2.3.0
       Compiling typeid v1.0.3
        Checking zeroize v1.8.1
        Checking data-encoding-macro v0.1.18
        Checking bcrypt-pbkdf v0.10.0
        Checking ssh-cipher v0.2.0
        Checking git-ref-format v0.3.1
        Checking sqlite3-sys v0.15.2
        Checking socks5-client v0.4.1
        Checking rand v0.8.5
        Checking hashbrown v0.15.3
        Checking signature v2.2.0
       Compiling serde_json v1.0.140
        Checking utf8parse v0.2.2
        Checking base-x v0.2.11
        Checking equivalent v1.0.2
       Compiling crossbeam-utils v0.8.21
        Checking radicle-std-ext v0.1.1
        Checking indexmap v2.9.0
        Checking multibase v0.9.1
        Checking anstyle-parse v0.2.7
        Checking ssh-key v0.6.7
        Checking qcheck v1.0.0
        Checking cyphernet v0.5.2
        Checking sqlite v0.32.0
        Checking radicle-ssh v0.10.0
        Checking anstyle-query v1.1.3
        Checking anstyle v1.0.11
        Checking colorchoice v1.0.4
        Checking ryu v1.0.20
        Checking itoa v1.0.15
        Checking is_terminal_polyfill v1.70.1
        Checking tinyvec_macros v0.1.1
        Checking memchr v2.7.4
        Checking tinyvec v1.9.0
        Checking anstream v0.6.19
        Checking erased-serde v0.4.6
        Checking radicle-dag v0.10.0
        Checking terminal_size v0.4.2
        Checking nonempty v0.9.0
        Checking once_cell v1.21.3
        Checking strsim v0.11.1
       Compiling heck v0.5.0
       Compiling thiserror v2.0.12
        Checking clap_lex v0.7.5
        Checking clap_builder v4.5.41
       Compiling clap_derive v4.5.41
        Checking tempfile v3.20.0
        Checking crossbeam-channel v0.5.15
        Checking serde-untagged v0.1.7
        Checking unicode-normalization v0.1.24
        Checking localtime v1.3.1
        Checking bytesize v2.0.1
       Compiling thiserror-impl v2.0.12
        Checking base64 v0.21.7
        Checking dunce v1.0.5
        Checking fast-glob v0.3.3
        Checking siphasher v1.0.1
        Checking clap v4.5.41
        Checking uuid v1.16.0
        Checking nonempty v0.11.0
        Checking git2 v0.19.0
        Checking radicle-git-ext v0.8.1
        Checking radicle-crypto v0.14.0
        Checking radicle-cob v0.17.0
        Checking radicle v0.20.0
        Checking radicle-job v0.4.0 (/workspace/src)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 01s
    [2025-12-20T14:13:22Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:13:22Z DEBUG ambient_ci::plan] RUN: Action CargoTest(
            CargoTest,
        )
    [2025-12-20T14:13:22Z DEBUG ambient_ci::action] RunnableAction::execute: self=CargoTest(
            CargoTest,
        )
    [2025-12-20T14:13:22Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    SPAWN: argv=["cargo", "--version"]
           cwd=/workspace/src (exists? true)
    cargo 1.92.0 (344c4567c 2025-10-21)
    SPAWN: argv=["cargo", "clippy", "--version"]
           cwd=/workspace/src (exists? true)
    clippy 0.1.92 (ded5c06cf2 2025-12-08)
    SPAWN: argv=["rustc", "--version"]
           cwd=/workspace/src (exists? true)
    rustc 1.92.0 (ded5c06cf 2025-12-08)
    SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
           cwd=/workspace/src (exists? true)
       Compiling typenum v1.18.0
       Compiling libc v0.2.172
       Compiling generic-array v0.14.7
       Compiling cfg-if v1.0.0
       Compiling subtle v2.6.1
       Compiling zerofrom v0.1.6
       Compiling stable_deref_trait v1.2.0
       Compiling yoke v0.8.0
       Compiling jobserver v0.1.33
       Compiling crypto-common v0.1.6
       Compiling serde v1.0.219
       Compiling cc v1.2.22
       Compiling zerovec v0.11.2
       Compiling block-padding v0.3.3
       Compiling block-buffer v0.10.4
       Compiling cpufeatures v0.2.17
       Compiling inout v0.1.4
       Compiling digest v0.10.7
       Compiling cipher v0.4.4
       Compiling tinystr v0.8.1
       Compiling getrandom v0.2.16
       Compiling litemap v0.8.0
       Compiling writeable v0.6.1
       Compiling zerotrie v0.2.2
       Compiling icu_locale_core v2.0.0
       Compiling potential_utf v0.1.2
       Compiling icu_collections v2.0.0
       Compiling icu_provider v2.0.0
       Compiling bitflags v2.9.0
       Compiling libz-sys v1.1.22
       Compiling thiserror v1.0.69
       Compiling icu_normalizer_data v2.0.0
       Compiling icu_properties_data v2.0.0
       Compiling universal-hash v0.5.1
       Compiling opaque-debug v0.3.1
       Compiling smallvec v1.15.0
       Compiling signature v1.6.4
       Compiling ed25519 v1.5.3
       Compiling icu_normalizer v2.0.0
       Compiling icu_properties v2.0.0
       Compiling ascii v1.1.0
       Compiling ct-codecs v1.1.5
       Compiling amplify_num v0.5.3
       Compiling ec25519 v0.1.0
       Compiling idna_adapter v1.2.1
       Compiling amplify v4.8.1
       Compiling polyval v0.6.2
       Compiling sha2 v0.10.9
       Compiling libgit2-sys v0.17.0+1.8.1
       Compiling sqlite3-src v0.5.1
       Compiling base64ct v1.7.3
       Compiling utf8_iter v1.0.4
       Compiling percent-encoding v2.3.1
       Compiling keccak v0.1.5
       Compiling pem-rfc7468 v0.7.0
       Compiling sha3 v0.10.8
       Compiling form_urlencoded v1.2.1
       Compiling idna v1.0.3
       Compiling ghash v0.5.1
       Compiling cyphergraphy v0.3.0
       Compiling git-ref-format-core v0.3.1
       Compiling rand_core v0.6.4
       Compiling aes v0.8.4
       Compiling ctr v0.9.2
       Compiling aead v0.5.2
       Compiling linux-raw-sys v0.9.4
       Compiling byteorder v1.5.0
       Compiling base32 v0.4.0
       Compiling cypheraddr v0.4.0
       Compiling blowfish v0.9.1
       Compiling rustix v1.0.7
       Compiling aes-gcm v0.10.3
       Compiling git-ref-format-macro v0.3.1
       Compiling url v2.5.4
       Compiling ssh-encoding v0.2.0
       Compiling poly1305 v0.8.0
       Compiling cbc v0.1.2
       Compiling chacha20 v0.9.1
       Compiling pbkdf2 v0.12.2
       Compiling log v0.4.27
       Compiling zeroize v1.8.1
       Compiling fastrand v2.3.0
       Compiling data-encoding v2.9.0
       Compiling data-encoding-macro v0.1.18
       Compiling bcrypt-pbkdf v0.10.0
       Compiling ssh-cipher v0.2.0
       Compiling git-ref-format v0.3.1
       Compiling sqlite3-sys v0.15.2
       Compiling socks5-client v0.4.1
       Compiling rand v0.8.5
       Compiling hashbrown v0.15.3
       Compiling radicle-std-ext v0.1.1
       Compiling signature v2.2.0
       Compiling utf8parse v0.2.2
       Compiling equivalent v1.0.2
       Compiling base-x v0.2.11
       Compiling multibase v0.9.1
       Compiling indexmap v2.9.0
       Compiling anstyle-parse v0.2.7
       Compiling ssh-key v0.6.7
       Compiling qcheck v1.0.0
       Compiling cyphernet v0.5.2
       Compiling sqlite v0.32.0
       Compiling getrandom v0.3.3
       Compiling typeid v1.0.3
       Compiling radicle-ssh v0.10.0
       Compiling memchr v2.7.4
       Compiling ryu v1.0.20
       Compiling tinyvec_macros v0.1.1
       Compiling anstyle-query v1.1.3
       Compiling is_terminal_polyfill v1.70.1
       Compiling anstyle v1.0.11
       Compiling colorchoice v1.0.4
       Compiling itoa v1.0.15
       Compiling serde_json v1.0.140
       Compiling anstream v0.6.19
       Compiling tinyvec v1.9.0
       Compiling erased-serde v0.4.6
       Compiling crossbeam-utils v0.8.21
       Compiling radicle-dag v0.10.0
       Compiling terminal_size v0.4.2
       Compiling nonempty v0.9.0
       Compiling strsim v0.11.1
       Compiling once_cell v1.21.3
       Compiling clap_lex v0.7.5
       Compiling clap_builder v4.5.41
       Compiling tempfile v3.20.0
       Compiling crossbeam-channel v0.5.15
       Compiling serde-untagged v0.1.7
       Compiling unicode-normalization v0.1.24
       Compiling git2 v0.19.0
       Compiling localtime v1.3.1
       Compiling bytesize v2.0.1
       Compiling dunce v1.0.5
       Compiling fast-glob v0.3.3
       Compiling siphasher v1.0.1
       Compiling base64 v0.21.7
       Compiling thiserror v2.0.12
       Compiling clap v4.5.41
       Compiling uuid v1.16.0
       Compiling nonempty v0.11.0
       Compiling radicle-git-ext v0.8.1
       Compiling radicle-crypto v0.14.0
       Compiling radicle-cob v0.17.0
       Compiling radicle v0.20.0
       Compiling radicle-job v0.4.0 (/workspace/src)
        Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 00s
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_job-238247049fb1fadf)
    
    running 4 tests
    test test::idempotent_create ... ok
    test test::missing_commit ... ok
    test test::runs_insertion_order_iteration ... ok
    test test::e2e ... ok
    
    test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.25s
    
         Running unittests src/bin/rad-job.rs (/workspace/cache/debug/deps/rad_job-a28eea8ffeb7fb71)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
       Doc-tests radicle_job
    
    running 1 test
    test src/lib.rs - (line 13) ... ok
    
    test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.18s
    
    [2025-12-20T14:15:25Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:15:25Z DEBUG ambient_ci::plan] RUN: Action TarCreate(
            TarCreate {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
        )
    [2025-12-20T14:15:25Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarCreate(
            TarCreate {
                archive: "/dev/vde",
                directory: "/workspace/cache",
            },
        )
    [2025-12-20T14:15:25Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    [2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vde",
            ),
            root: Some(
                "/workspace/cache",
            ),
            size: None,
        }
    [2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vde; exists? true
    [2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] create archive file /dev/vde
    [2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] directory /workspace/cache exists? true
    [2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] add contents of /workspace/cache as .
    [2025-12-20T14:15:25Z TRACE ambient_ci::vdrive] calling tar_create
    [2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] created virtual drive /dev/vde
    [2025-12-20T14:15:36Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:15:36Z DEBUG ambient_ci::plan] RUN: Action TarCreate(
            TarCreate {
                archive: "/dev/vdd",
                directory: "/workspace/artifacts",
            },
        )
    [2025-12-20T14:15:36Z DEBUG ambient_ci::action] RunnableAction::execute: self=TarCreate(
            TarCreate {
                archive: "/dev/vdd",
                directory: "/workspace/artifacts",
            },
        )
    [2025-12-20T14:15:36Z DEBUG ambient_ci::action] RunnableAction::execute: context=Context {
            envs: {
                "HOME": "/root",
                "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",
            },
            source_dir: "/workspace/src",
            deps_dir: "/workspace/deps",
            artifacts_dir: "/workspace/artifacts",
        }
    [2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] creating virtual drive (tar archive): VirtualDriveBuilder {
            filename: Some(
                "/dev/vdd",
            ),
            root: Some(
                "/workspace/artifacts",
            ),
            size: None,
        }
    [2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] tar archive to be created: /dev/vdd; exists? true
    [2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] create archive file /dev/vdd
    [2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] directory /workspace/artifacts exists? true
    [2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] add contents of /workspace/artifacts as .
    [2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] calling tar_create
    [2025-12-20T14:15:36Z TRACE ambient_ci::vdrive] created virtual drive /dev/vdd
    [2025-12-20T14:15:36Z DEBUG ambient_ci::plan] RUN: Action finished OK
    [2025-12-20T14:15:36Z DEBUG ambient_ci::plan] All actions were performed successfully
    ambient-execute-plan ends
    EXIT CODE: 0
    
    ====================
[2025-12-20T14:15:39Z DEBUG ambient_ci::qemu] QEMU: CI run under exit code 0
[2025-12-20T14:15:39Z DEBUG ambient_ci::run] CI run exit code from QEMU: 0
[2025-12-20T14:15:39Z DEBUG ambient_ci::run] remove old cache
[2025-12-20T14:15:40Z DEBUG ambient_ci::run] extract cache
[2025-12-20T14:15:44Z DEBUG ambient_ci::run] Executing post-plan steps
[2025-12-20T14:15:44Z DEBUG ambient_ci::plan] All actions were performed successfully
[2025-12-20T14:15:44Z DEBUG ambient_ci::project] write project state to /home/_rad/ambient-state/rad:z2UcCU1LgMshWvXj6hXSDDrwB8q8M/meta.yaml
[2025-12-20T14:15:45Z INFO  ambient] ambient ends successfully

Ambient stderr

<empty log>