CI run for rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5

Radicle repository id rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5

Adapter run ID: aaca58fb-4648-49e7-9715-7bf8ecc94382
Result: failure
Duration: 404 seconds seconds
2025-09-20 11:04:42Z -- 2025-09-20 11:11:26Z

Run log
Trigger message
Ambient stdout
Ambient stderr

Run log

[2025-09-20T11:11:26Z INFO  ambient] ambient starts
deprecated: the `cpus` field is replaced by `qemu.cpus`
deprecated: the `memory` field is replaced by `qemu.memory`
[2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01HBdsDxe: 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)
[0m[30m[47mWelcome to GRUB!


[0m[37m[40m[0m[30m[40m[2J[01;01H[0m[37m[40mINFO: 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  28.8M  1 disk 
vdd    254:48   0 953.7M  0 disk 
vde    254:64   0  27.9G  0 disk 
vdf    254:80   0   1.3G  1 disk 
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad         0 2025-09-20 11:04 ./
-rw-r--r-- _rad/_rad      2416 2025-09-20 11:04 ./plan.yaml
-rwxr-xr-x _rad/_rad   2994840 2025-09-20 11:04 ./run-ci
INFO: Extracted files:
  6291466      4 drwxr-xr-x   2 1000     1000         4096 Sep 20 11:04 .
  6291468   2928 -rwxr-xr-x   1 1000     1000      2994840 Sep 20 11:04 ./run-ci
  6291467      4 -rw-r--r--   1 1000     1000         2416 Sep 20 11:04 ./plan.yaml
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-09-20T11:05:07Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-09-20T11:05:07Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-09-20T11:05:07Z INFO  ambient_execute_plan] ambient-execute-plan starts
[2025-09-20T11:05:07Z WARN  ambient_execute_plan] ambient-execute-plan starts
[2025-09-20T11:05:07Z 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: cargo_test
- action: shell
  shell: |
    # Because of a (temporary) limitation in Ambient, we need to set
    # these variables manually. Once Ambient manages environment
    # variables better, these can be deleted.
    export CARGO_TARGET_DIR=/workspace/cache
    export CARGO_HOME=/workspace/deps
    export HOME=/root
    export PATH="/root/.cargo/bin:$PATH"

    cargo doc --workspace --no-deps
- action: shell
  shell: |
    # Because of a (temporary) limitation in Ambient, we need to set
    # these variables manually. Once Ambient manages environment
    # variables better, these can be deleted.
    export CARGO_TARGET_DIR=/workspace/cache
    export CARGO_HOME=/workspace/deps
    export HOME=/root
    export PATH="/root/.cargo/bin:$PATH"

    # These are based on debian/control.
    export DEBEMAIL=liw@liw.fi
    export DEBFULLNAME="Lars Wirzenius"

    # Clean up after tests and documentation building. The Debian
    # package building tools do not want changes outside the
    # `debian` directory, compared to what is committed to Git, from
    # which the "upstream tarball" is created.
    git reset --hard
    git clean -fdx
    git status --ignored

    # Update debian/changelog with a new version so that every run
    # creates a newer version. This avoids us having to update the
    # file manually for every CI run.
    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-09-20T11:05:07Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace",
    }
RUN: Action finished OK
RUN: Action Mkdir {
    pathname: "/workspace/artifacts",
}
[2025-09-20T11:05:07Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
        pathname: "/workspace/artifacts",
    }
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdc",
    directory: "/workspace/src",
}
[2025-09-20T11:05:07Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
[2025-09-20T11:05:07Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-09-20T11:05:07Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vdf",
    directory: "/workspace/deps",
}
[2025-09-20T11:05:07Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
[2025-09-20T11:05:07Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-09-20T11:05:11Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
    archive: "/dev/vde",
    directory: "/workspace/cache",
}
[2025-09-20T11:05:11Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
[2025-09-20T11:05:11Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-09-20T11:05:57Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
    argv: [
        "find",
        "/workspace",
        "-maxdepth",
        "2",
        "-ls",
    ],
}
[2025-09-20T11:05:57Z 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=[]
  2621441      4 drwxr-xr-x   6 root     root         4096 Sep 20 11:05 /workspace
  2621442      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:05 /workspace/artifacts
  2753993      4 drwxr-xr-x   6 root     root         4096 Sep 19 13:49 /workspace/cache
  2764295      4 drwxr-xr-x  30 root     root         4096 Sep 20 09:48 /workspace/cache/doc
  2769493      4 drwxr-xr-x   7 root     root         4096 Sep 20 09:44 /workspace/cache/debug
  2753994      4 -rw-r--r--   1 root     root         1032 Sep 20 09:44 /workspace/cache/.rustc_info.json
  2753995      4 drwxr-xr-x   7 root     root         4096 Sep 20 09:51 /workspace/cache/release
  2769491      4 drwxr-xr-x   2 root     root         4096 Sep 19 13:39 /workspace/cache/tmp
  2769492      4 -rw-r--r--   1 root     root          218 Sep 20 09:03 /workspace/cache/.rustdoc_fingerprint.json
  2623225      4 drwxr-xr-x   3 root     root         4096 Sep 20 11:04 /workspace/deps
  2669918      0 -rw-r--r--   1 root     root            0 Sep 10 13:40 /workspace/deps/.package-cache
  2623226      4 drwxr-xr-x   5 root     root         4096 Sep 10 13:40 /workspace/deps/registry
  2669916    180 -rw-r--r--   1 root     root       184320 Sep 20 11:04 /workspace/deps/.global-cache
  2669917      0 -rw-r--r--   1 root     root            0 Sep 10 13:40 /workspace/deps/.package-cache-mutate
  2621443      4 drwxr-xr-x  12 root     root         4096 Sep 20 11:04 /workspace/src
  2622030     12 -rw-r--r--   1 root     root         9723 Sep 20 11:04 /workspace/src/LICENSE-APACHE
  2621448      8 -rw-r--r--   1 root     root         5666 Sep 20 11:04 /workspace/src/rad-id.1.adoc
  2621445      4 -rw-r--r--   1 root     root           77 Sep 20 11:04 /workspace/src/rust-toolchain.toml
  2621450      8 -rw-r--r--   1 root     root         5102 Sep 20 11:04 /workspace/src/flake.lock
  2621456      4 drwxr-xr-x   5 root     root         4096 Sep 20 11:04 /workspace/src/systemd
  2622037      4 -rw-r--r--   1 root     root         1083 Sep 20 11:04 /workspace/src/.gitsigners
  2622039      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/.github
  2622034      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/.radicle
  2622032      4 -rw-r--r--   1 root     root         1203 Sep 20 11:04 /workspace/src/DCO
  2621452      4 -rw-r--r--   1 root     root         2397 Sep 20 11:04 /workspace/src/Cargo.toml
  2621446      8 -rw-r--r--   1 root     root         8022 Sep 20 11:04 /workspace/src/rad.1.adoc
  2621476      8 -rw-r--r--   1 root     root         5432 Sep 20 11:04 /workspace/src/deny.toml
  2621454     12 -rw-r--r--   1 root     root         8322 Sep 20 11:04 /workspace/src/CONTRIBUTING.md
  2621451      4 -rw-r--r--   1 root     root         3091 Sep 20 11:04 /workspace/src/README.md
  2622042      4 -rw-r--r--   1 root     root           61 Sep 20 11:04 /workspace/src/.env.seed
  2621475      4 -rw-r--r--   1 root     root          571 Sep 20 11:04 /workspace/src/git-remote-rad.1.adoc
  2621447     12 -rw-r--r--   1 root     root        10770 Sep 20 11:04 /workspace/src/rad-patch.1.adoc
  2622041      4 -rw-r--r--   1 root     root           42 Sep 20 11:04 /workspace/src/.envrc
  2622038      4 -rw-r--r--   1 root     root          101 Sep 20 11:04 /workspace/src/.gitignore
  2621449     12 -rw-r--r--   1 root     root         8803 Sep 20 11:04 /workspace/src/flake.nix
  2622027      4 -rw-r--r--   1 root     root         1623 Sep 20 11:04 /workspace/src/build.rs
  2622028      4 -rw-r--r--   1 root     root         2163 Sep 20 11:04 /workspace/src/VERSIONING.md
  2622033      4 -rw-r--r--   1 root     root           79 Sep 20 11:04 /workspace/src/ARCHITECTURE.md
  2621455      8 -rw-r--r--   1 root     root         4304 Sep 20 11:04 /workspace/src/CHANGELOG.md
  2622044      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/.config
  2621453     96 -rw-r--r--   1 root     root        95685 Sep 20 11:04 /workspace/src/Cargo.lock
  2621477      4 drwxr-xr-x   3 root     root         4096 Sep 20 11:04 /workspace/src/debian
  2622048      4 drwxr-xr-x   8 root     root         4096 Sep 20 11:04 /workspace/src/.git
  2622031      8 -rw-r--r--   1 root     root         7645 Sep 20 11:04 /workspace/src/HACKING.md
  2622029      4 -rw-r--r--   1 root     root         1079 Sep 20 11:04 /workspace/src/LICENSE-MIT
  2621474      4 -rw-r--r--   1 root     root          426 Sep 20 11:04 /workspace/src/radicle-node.1.adoc
  2622014      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/build
  2621444      0 -rw-r--r--   1 root     root            0 Sep 20 11:04 /workspace/src/test
  2621463      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/scripts
  2622046      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/.cargo
  2621490      4 drwxr-xr-x  16 root     root         4096 Sep 20 11:04 /workspace/src/crates
  2622043      4 -rw-r--r--   1 root     root           30 Sep 20 11:04 /workspace/src/.dockerignore
RUN: Action finished OK
RUN: Action CargoFmt
[2025-09-20T11:05:57Z 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-09-20T11:05:58Z 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")]
    Checking radicle-ssh v0.9.0 (/workspace/src/crates/radicle-ssh)
    Checking radicle-dag v0.10.0 (/workspace/src/crates/radicle-dag)
    Checking radicle-signals v0.11.0 (/workspace/src/crates/radicle-signals)
    Checking radicle-crypto v0.12.0 (/workspace/src/crates/radicle-crypto)
   Compiling radicle-cli v0.13.0 (/workspace/src/crates/radicle-cli)
    Checking radicle-cob v0.14.0 (/workspace/src/crates/radicle-cob)
    Checking radicle-term v0.12.0 (/workspace/src/crates/radicle-term)
   Compiling radicle-node v0.11.0 (/workspace/src/crates/radicle-node)
    Checking radicle v0.15.0 (/workspace/src/crates/radicle)
    Checking radicle-systemd v0.9.0 (/workspace/src/crates/radicle-systemd)
   Compiling radicle-remote-helper v0.10.0 (/workspace/src/crates/radicle-remote-helper)
    Checking radicle-fetch v0.11.0 (/workspace/src/crates/radicle-fetch)
    Checking radicle-cli-test v0.11.0 (/workspace/src/crates/radicle-cli-test)
    Checking radicle-schemars v0.1.0 (/workspace/src/crates/radicle-schemars)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 14.50s
RUN: Action finished OK
RUN: Action CargoBuild
[2025-09-20T11:06:13Z 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 radicle-ssh v0.9.0 (/workspace/src/crates/radicle-ssh)
   Compiling radicle-dag v0.10.0 (/workspace/src/crates/radicle-dag)
   Compiling radicle-crypto v0.12.0 (/workspace/src/crates/radicle-crypto)
   Compiling radicle-signals v0.11.0 (/workspace/src/crates/radicle-signals)
   Compiling radicle-cob v0.14.0 (/workspace/src/crates/radicle-cob)
   Compiling radicle-cli v0.13.0 (/workspace/src/crates/radicle-cli)
   Compiling radicle v0.15.0 (/workspace/src/crates/radicle)
   Compiling radicle-term v0.12.0 (/workspace/src/crates/radicle-term)
   Compiling radicle-node v0.11.0 (/workspace/src/crates/radicle-node)
   Compiling radicle-systemd v0.9.0 (/workspace/src/crates/radicle-systemd)
   Compiling radicle-remote-helper v0.10.0 (/workspace/src/crates/radicle-remote-helper)
   Compiling radicle-fetch v0.11.0 (/workspace/src/crates/radicle-fetch)
   Compiling radicle-cli-test v0.11.0 (/workspace/src/crates/radicle-cli-test)
   Compiling radicle-schemars v0.1.0 (/workspace/src/crates/radicle-schemars)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 48.08s
RUN: Action finished OK
RUN: Action CargoTest
[2025-09-20T11:07:01Z DEBUG ambient_ci::action] Plan::execute: CargoTest
SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
       cwd=/workspace/src (exists? true)
       extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.49s
     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle-b4bf1c275801b632)

running 188 tests
test canonical::formatter::test::ascii_control_characters ... ok
test canonical::formatter::test::securesystemslib_asserts ... ok
test canonical::formatter::test::ordered_nested_object ... ok
test cob::cache::migrations::_2::tests::test_migration_2 ... ok
test cob::cache::tests::test_check_version ... ok
test cob::cache::tests::test_migrate_to ... ok
test cob::cache::migrations::_2::tests::test_patch_json_deserialization ... ok
test cob::common::test::test_color ... ok
test cob::common::test::test_emojis ... ok
test cob::identity::test::prop_json_eq_str ... ok
test cob::identity::test::test_identity_redact_revision ... ok
test cob::identity::test::test_identity_reject_concurrent ... ok
test cob::identity::test::test_identity_remove_delegate_concurrent ... ok
test cob::identity::test::test_identity_update_rejected ... ok
test cob::identity::test::test_identity_updates ... ok
test cob::identity::test::test_identity_updates_concurrent ... ok
test cob::identity::test::test_identity_updates_concurrent_outdated ... ok
test cob::issue::cache::tests::test_counts ... ok
test cob::issue::cache::tests::test_get ... ok
test cob::issue::cache::tests::test_is_empty ... ok
test cob::issue::cache::tests::test_list ... ok
test cob::issue::cache::tests::test_list_by_status ... ok
test cob::issue::cache::tests::test_remove ... ok
test cob::identity::test::test_valid_identity ... ok
test cob::issue::test::test_embeds ... ok
test cob::issue::test::test_embeds_edit ... ok
test cob::issue::test::test_invalid_actions ... ok
test cob::issue::test::test_concurrency ... ok
test cob::issue::test::test_invalid_cob ... ok
test cob::issue::test::test_invalid_tx ... ok
test cob::issue::test::test_invalid_tx_reference ... ok
test cob::issue::test::test_issue_all ... ok
test cob::issue::test::test_issue_comment ... ok
test cob::issue::test::test_issue_comment_redact ... ok
test cob::issue::test::test_issue_create_and_assign ... ok
test cob::issue::test::test_issue_create_and_get ... ok
test cob::issue::test::test_issue_create_and_change_state ... ok
test cob::issue::test::test_issue_create_and_unassign ... ok
test cob::issue::test::test_issue_create_and_reassign ... ok
test cob::issue::test::test_issue_edit ... ok
test cob::issue::test::test_issue_edit_description ... ok
test cob::issue::test::test_issue_label ... ok
test cob::issue::test::test_issue_multilines ... ok
test cob::issue::test::test_issue_react ... ok
test cob::issue::test::test_issue_state_serde ... ok
test cob::issue::test::test_ordering ... ok
test cob::issue::test::test_issue_reply ... ok
test cob::patch::cache::tests::test_counts ... ok
test cob::patch::cache::tests::test_get ... ok
test cob::patch::cache::tests::test_is_empty ... ok
test cob::patch::cache::tests::test_list ... ok
test cob::patch::cache::tests::test_list_by_status ... ok
test cob::patch::cache::tests::test_remove ... ok
test cob::patch::test::test_json ... ok
test cob::patch::test::test_json_serialization ... ok
test cob::patch::test::test_patch_create_and_get ... ok
test cob::patch::test::test_patch_discussion ... ok
test cob::patch::test::test_patch_merge ... ok
test cob::patch::test::test_patch_redact ... ok
test cob::patch::test::test_patch_review ... ok
test cob::patch::test::test_patch_review_comment ... ok
test cob::patch::test::test_patch_review_duplicate ... ok
test cob::patch::test::test_patch_review_edit ... ok
test cob::patch::test::test_patch_review_edit_comment ... ok
test cob::patch::test::test_patch_review_remove_summary ... ok
test cob::patch::cache::tests::test_find_by_revision ... ok
test cob::patch::test::test_patch_review_revision_redact ... ok
test cob::patch::test::test_reactions_json_serialization ... ok
test cob::patch::test::test_revision_edit_redact ... ok
test cob::patch::test::test_revision_reaction ... ok
test cob::patch::test::test_revision_review_merge_redacted ... ok
test cob::patch::test::test_patch_update ... ok
test cob::thread::tests::test_comment_edit_missing ... ok
test cob::thread::tests::test_comment_edit_redacted ... ok
test cob::thread::tests::test_comment_redact_missing ... ok
test cob::thread::tests::test_duplicate_comments ... ok
test cob::thread::tests::test_edit_comment ... ok
test cob::thread::tests::test_redact_comment ... ok
test cob::thread::tests::test_timeline ... ok
test git::canonical::tests::test_quorum ... ok
test git::canonical::tests::test_quorum_merges ... ok
test git::canonical::tests::test_quorum_properties ... ok
test git::test::test_version_from_str ... ok
test git::test::test_version_ord ... ok
test identity::did::test::test_did_encode_decode ... ok
test identity::did::test::test_did_vectors ... ok
test identity::doc::id::test::prop_from_str ... ok
test cob::thread::tests::prop_ordering ... ok
test identity::doc::test::test_canonical_doc ... ok
test identity::doc::test::test_canonical_example ... ok
test identity::doc::test::test_duplicate_dids ... ok
test identity::doc::test::test_future_version_error ... ok
test identity::doc::test::test_is_valid_version ... ok
test identity::doc::test::test_max_delegates ... ok
test identity::doc::test::test_not_found ... ok
test identity::doc::test::test_parse_version ... ok
test identity::doc::test::test_visibility_json ... ok
test identity::project::test::test_project_name ... ok
test node::address::store::test::test_alias ... ok
test node::address::store::test::test_disconnected ... ok
test node::address::store::test::test_disconnected_ban ... ok
test node::address::store::test::test_empty ... ok
test node::address::store::test::test_entries ... ok
test node::address::store::test::test_get_none ... ok
test node::address::store::test::test_insert_and_get ... ok
test node::address::store::test::test_insert_and_remove ... ok
test node::address::store::test::test_insert_and_update ... ok
test node::address::store::test::test_insert_duplicate ... ok
test node::address::store::test::test_node_aliases ... ok
test node::address::store::test::test_remove_nothing ... ok
test node::db::test::test_version ... ok
test node::features::test::test_operations ... ok
test node::notifications::store::test::test_branch_notifications ... ok
test node::notifications::store::test::test_clear ... ok
test node::notifications::store::test::test_cob_notifications ... ok
test node::notifications::store::test::test_counts_by_repo ... ok
test node::notifications::store::test::test_duplicate_notifications ... ok
test node::notifications::store::test::test_notification_status ... ok
test node::policy::store::test::test_follow_and_unfollow_node ... ok
test node::policy::store::test::test_node_aliases ... ok
test node::policy::store::test::test_node_policies ... ok
test node::policy::store::test::test_node_policy ... ok
test node::policy::store::test::test_repo_policies ... ok
test node::policy::store::test::test_repo_policy ... ok
test node::policy::store::test::test_seed_and_unseed_repo ... ok
test node::policy::store::test::test_update_alias ... ok
test node::policy::store::test::test_update_scope ... ok
test node::refs::store::test::test_count ... ok
test node::refs::store::test::test_set_and_delete ... ok
test node::refs::store::test::test_set_and_get ... ok
test node::routing::test::test_count ... ok
test node::routing::test::test_entries ... ok
test node::routing::test::test_insert_and_get ... ok
test node::routing::test::test_insert_and_get_resources ... ok
test node::routing::test::test_insert_and_remove ... ok
test node::routing::test::test_insert_duplicate ... ok
test node::routing::test::test_insert_existing_updated_time ... ok
test node::routing::test::test_len ... ok
test node::routing::test::test_prune ... ok
test node::routing::test::test_remove_many ... ok
test node::routing::test::test_remove_redundant ... ok
test node::routing::test::test_update_existing_multi ... ok
test node::sync::announce::test::announcer_must_reach_preferred_seeds ... ok
test node::sync::announce::test::announcer_reached_max_replication_target ... ok
test node::sync::announce::test::announcer_reached_min_replication_target ... ok
test node::sync::announce::test::announcer_timed_out ... ok
test node::sync::announce::test::announcer_will_minimise_replication_factor ... ok
test node::sync::announce::test::cannot_construct_announcer ... ok
test node::sync::fetch::test::all_nodes_are_candidates ... ok
test node::sync::fetch::test::all_nodes_are_fetchable ... ok
test node::sync::fetch::test::could_not_reach_target ... ok
test node::sync::fetch::test::ignores_duplicates_and_local_node ... ok
test node::sync::fetch::test::preferred_seeds_target_returned_over_replicas ... ok
test node::sync::fetch::test::reaches_target_of_max_replicas ... ok
test node::sync::fetch::test::reaches_target_of_preferred_seeds ... ok
test node::sync::fetch::test::reaches_target_of_replicas ... ok
test node::sync::test::ensure_replicas_construction ... ok
test node::sync::test::replicas_constrain_to ... ok
test node::test::test_alias ... ok
test node::test::test_command_result ... ok
test node::test::test_user_agent ... ok
test node::timestamp::tests::test_timestamp_max ... ok
test profile::config::test::schema ... ok
test profile::test::canonicalize_home ... ok
test profile::test::test_config ... ok
test rad::tests::test_checkout ... ok
test rad::tests::test_fork ... ok
test rad::tests::test_init ... ok
test serde_ext::test::test_localtime ... ok
test serde_ext::test::test_localtime_ext ... ok
test storage::git::tests::test_references_of ... ok
test storage::git::tests::test_remote_refs ... ok
test storage::git::tests::test_sign_refs ... ok
test storage::git::transport::local::url::test::test_url_parse ... ok
test storage::git::transport::local::url::test::test_url_to_string ... ok
test storage::git::transport::remote::url::test::test_url_parse ... ok
test storage::refs::tests::prop_canonical_roundtrip ... ok
test storage::refs::tests::test_rid_verification ... ok
test storage::tests::test_storage ... ok
test test::assert::test::assert_with_message ... ok
test test::assert::test::test_assert_no_move ... ok
test test::assert::test::test_assert_panic_0 - should panic ... ok
test test::assert::test::test_assert_panic_1 - should panic ... ok
test test::assert::test::test_assert_panic_2 - should panic ... ok
test test::assert::test::test_assert_succeed ... ok
test test::assert::test::test_panic_message ... ok
test version::test::test_version ... ok
test identity::doc::test::prop_encode_decode ... ok

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

     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cli-c4082d6a1fa8a293)

running 14 tests
test commands::rad_patch::review::builder::tests::test_review_comments_basic ... ok
test commands::rad_patch::review::builder::tests::test_review_comments_before ... ok
test commands::rad_patch::review::builder::tests::test_review_comments_multiline ... ok
test commands::rad_patch::review::builder::tests::test_review_comments_split_hunk ... ok
test git::ddiff::tests::diff_encode_decode_ddiff_hunk ... ok
test git::pretty_diff::test::test_pretty ... ignored
test git::unified_diff::test::test_diff_content_encode_decode_content ... ok
test git::unified_diff::test::test_diff_encode_decode_diff ... ok
test terminal::format::test::test_bytes ... ok
test terminal::format::test::test_strip_comments ... ok
test commands::rad_inspect::test::test_tree ... ok
test terminal::patch::test::test_edit_display_message ... ok
test terminal::patch::test::test_create_display_message ... ok
test terminal::patch::test::test_update_display_message ... ok

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

     Running unittests src/main.rs (/workspace/cache/debug/deps/rad-ada175d36f8264d9)

running 0 tests

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

     Running tests/commands.rs (/workspace/cache/debug/deps/commands-1e8a24d540410ee4)

running 96 tests
test framework_home ... ok
test git_push_amend ... ok
test git_push_and_fetch ... ok
test git_push_diverge ... ok
test git_push_converge ... ok
test git_push_rollback ... ok
test rad_auth ... ok
test rad_auth_errors ... ok
test rad_block ... ok
test git_tag ... ok
test rad_checkout ... ok
test rad_clone ... ok
test rad_clean ... ok
test rad_clone_all ... ok
test rad_clone_connect ... ok
test rad_clone_directory ... ok
test rad_clone_unknown ... ok
test rad_clone_partial_fail ... ok
test rad_cob_log ... ok
test rad_cob_migrate ... ok
test rad_cob_multiset ... ok
test rad_cob_show ... ok
test rad_cob_update ... ok
test rad_cob_update_identity ... ok
test rad_diff ... ok
test rad_config ... ok
test rad_fetch ... ok
test rad_fork ... ok
test rad_id_collaboration ... ignored, slow
test rad_id ... ok
test rad_id_conflict ... ok
test rad_id_private ... ok
test rad_id_multi_delegate ... ok
test rad_id_threshold ... ok
test rad_id_unknown_field ... ok
test rad_id_threshold_soft_fork ... ok
test rad_id_update_delete_field ... ok
test rad_init ... ok
test rad_init_existing ... ok
test rad_init_no_git ... ok
test rad_init_no_seed ... ok
test rad_init_private ... ok
test rad_inbox ... ok
test rad_init_private_clone ... ok
test rad_init_private_clone_seed ... ok
test rad_init_private_no_seed ... ok
test rad_init_private_seed ... ok
test rad_init_sync_not_connected ... ok
test rad_init_sync_preferred ... ok
test rad_init_sync_and_clone ... ok
test rad_init_with_existing_remote ... ok
test rad_inspect ... ok
test rad_issue ... ok
test rad_merge_after_update ... ok
test rad_init_sync_timeout ... ok
test rad_merge_no_ff ... ok
test rad_merge_via_push ... ok
test rad_node_connect ... ok
test rad_node ... ok
test rad_patch_ahead_behind ... ok
test rad_patch ... ok
test rad_patch_change_base ... ok
test rad_patch_checkout ... ok
test rad_patch_checkout_revision ... ok
test rad_patch_checkout_force ... ok
test rad_patch_detached_head ... ok
test rad_patch_diff ... ok
test rad_patch_draft ... ok
test rad_patch_edit ... ok
test rad_patch_delete ... ok
test rad_patch_fetch_2 ... ok
test rad_patch_fetch_1 ... ok
test rad_patch_merge_draft ... ok
test rad_patch_open_explore ... ok
test rad_patch_revert_merge ... ok
test rad_patch_update ... ok
test rad_patch_via_push ... ok
test rad_publish ... ok
test rad_patch_pull_update ... ok
test rad_push_and_pull_patches ... ok
test rad_review_by_hunk ... ok
test rad_seed_and_follow ... ok
test rad_remote ... ok
test rad_self ... ok
test rad_seed_many ... ok
test rad_sync_without_node ... ok
test rad_unseed ... ok
test rad_sync ... ok
test rad_warn_old_nodes ... ok
test rad_unseed_many ... ok
test rad_watch ... ok
test test_clone_without_seeds ... ok
test test_cob_deletion ... ok
test rad_workflow ... ok
test test_cob_replication ... ok
test test_replication_via_seed ... ok

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

     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cli_test-354ae0a9d473645d)

running 3 tests
test tests::test_parse ... ok
test tests::test_run ... ok
test tests::test_example_spaced_brackets ... ok

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

     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cob-e85912e820568959)

running 8 tests
test object::tests::test_serde ... ok
test tests::invalid_parse_refstr ... ok
test tests::parse_refstr ... ok
test tests::roundtrip ... ok
test tests::list_cobs ... ok
test tests::update_cob ... ok
test type_name::test::valid_typenames ... ok
test tests::traverse_cobs ... ok

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

     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_crypto-5617725847bbf5b6)

running 12 tests
test ssh::fmt::test::test_key ... ok
test ssh::fmt::test::test_fingerprint ... ok
test ssh::keystore::tests::test_init_no_passphrase ... ok
test ssh::keystore::tests::test_signer ... ok
test ssh::test::prop_encode_decode_sk ... ok
test ssh::test::test_agent_encoding_remove ... ok
test ssh::test::test_agent_encoding_sign ... ok
test tests::prop_encode_decode ... ok
test tests::prop_key_equality ... ok
test tests::test_e25519_dh ... ok
test tests::test_encode_decode ... ok
test ssh::keystore::tests::test_init_passphrase ... ok

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

     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_dag-20ca1aca3d76c348)

running 20 tests
test tests::test_contains ... ok
test tests::test_cycle ... ok
test tests::test_dependencies ... ok
test tests::test_diamond ... ok
test tests::test_fold_diamond ... ok
test tests::test_fold_multiple_roots ... ok
test tests::test_fold_reject ... ok
test tests::test_fold_sorting_1 ... ok
test tests::test_fold_sorting_2 ... ok
test tests::test_get ... ok
test tests::test_is_empty ... ok
test tests::test_len ... ok
test tests::test_merge_1 ... ok
test tests::test_merge_2 ... ok
test tests::test_prune_1 ... ok
test tests::test_prune_2 ... ok
test tests::test_prune_by_sorting ... ok
test tests::test_remove ... ok
test tests::test_siblings ... ok
test tests::test_complex ... ok

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

     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_fetch-d2fb41089fc1c71d)

running 0 tests

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

     Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_node-b756a84f7d576953)

running 113 tests
test control::tests::test_control_socket ... ok
test deserializer::test::prop_decode_next ... ok
test deserializer::test::test_decode_next ... ok
test deserializer::test::test_unparsed ... ok
test service::filter::test::test_parameters ... ok
test control::tests::test_seed_unseed ... ok
test service::filter::test::test_sizes ... ok
test service::limiter::test::test_limitter_different_rates ... ok
test service::limiter::test::test_limitter_multi ... ok
test service::gossip::store::test::test_announced ... ok
test service::limiter::test::test_limitter_refill ... ok
test service::message::tests::test_inventory_limit ... ok
test service::message::tests::prop_refs_announcement_signing ... ok
test service::message::tests::test_ref_remote_limit ... ok
test tests::e2e::missing_default_branch ... ok
test service::message::tests::test_node_announcement_validate ... ok
test tests::e2e::missing_delegate_default_branch ... ok
test tests::e2e::test_background_foreground_fetch ... ok
test tests::e2e::test_catchup_on_refs_announcements ... ok
test tests::e2e::test_channel_reader_limit ... ok
test tests::e2e::test_clone ... ok
test tests::e2e::test_connection_crossing ... FAILED
test tests::e2e::test_dont_fetch_owned_refs ... ok
test tests::e2e::test_concurrent_fetches ... ok
test tests::e2e::test_fetch_followed_remotes ... ok
test tests::e2e::test_fetch_preserve_owned_refs ... ok
test tests::e2e::test_fetch_unseeded ... ok
test tests::e2e::test_fetch_up_to_date ... ok
test tests::e2e::test_inventory_sync_basic ... ok
test tests::e2e::test_inventory_sync_bridge ... ok
test tests::e2e::test_inventory_sync_ring ... ok
test tests::e2e::test_large_fetch ... ok
test tests::e2e::test_migrated_clone ... ok
test tests::e2e::test_missing_remote ... ok
test tests::e2e::test_multiple_offline_inits ... ok
test tests::e2e::test_inventory_sync_star ... ok
test tests::e2e::test_non_fastforward_sigrefs ... ok
test tests::e2e::test_outdated_delegate_sigrefs ... ok
test tests::e2e::test_replication ... ok
test tests::e2e::test_outdated_sigrefs ... ok
test tests::e2e::test_replication_invalid ... ok
test tests::e2e::test_replication_ref_in_sigrefs ... ok
test tests::prop_inventory_exchange_dense ... ok
test tests::test_announcement_rebroadcast ... ok
test tests::test_announcement_rebroadcast_duplicates ... ok
test tests::test_announcement_rebroadcast_timestamp_filtered ... ok
test tests::test_announcement_message_amplification ... ok
test tests::test_connection_kept_alive ... ok
test tests::test_announcement_relay ... ok
test tests::test_disconnecting_unresponsive_peer ... ok
test tests::test_fetch_missing_inventory_on_gossip ... ok
test tests::test_fetch_missing_inventory_on_schedule ... ok
test tests::test_inbound_connection ... ok
test tests::test_inventory_decode ... ok
test tests::test_init_and_seed ... ok
test tests::test_inventory_relay ... ok
test tests::test_inventory_relay_bad_timestamp ... ok
test tests::test_inventory_sync ... ok
test tests::test_maintain_connections ... ok
test tests::test_maintain_connections_failed_attempt ... ok
test tests::test_maintain_connections_transient ... ok
test tests::test_outbound_connection ... ok
test tests::test_persistent_peer_connect ... ok
test tests::test_inventory_pruning ... ok
test tests::test_persistent_peer_reconnect_success ... ok
test tests::test_persistent_peer_reconnect_attempt ... ok
test tests::test_ping_response ... ok
test tests::test_queued_fetch_from_ann_same_rid ... ok
test tests::test_queued_fetch_from_command_same_rid ... ok
test tests::test_queued_fetch_max_capacity ... ok
test tests::test_redundant_connect ... ok
test tests::test_refs_announcement_followed ... ok
test tests::test_refs_announcement_fetch_trusted_no_inventory ... ok
test tests::test_refs_announcement_no_subscribe ... ok
test tests::test_refs_announcement_offline ... ok
test tests::test_refs_announcement_relay_private ... ok
test tests::test_refs_announcement_relay_public ... ok
test tests::test_refs_synced_event ... ok
test tests::test_seed_repo_subscribe ... ok
test wire::frame::test::test_stream_id ... ok
test tests::test_seeding ... ok
test wire::message::tests::prop_addr ... ok
test wire::message::tests::prop_message_encode_decode ... ok
test wire::message::tests::prop_zero_bytes_encode_decode ... ok
test wire::message::tests::test_inv_ann_max_size ... ok
test wire::message::tests::test_node_ann_max_size ... ok
test wire::message::tests::test_pingpong_encode_max_size ... ok
test wire::message::tests::test_pingpong_encode_size_overflow ... ok
test wire::message::tests::test_refs_ann_max_size ... ok
test wire::protocol::test::test_inventory_ann_with_extension ... ok
test wire::protocol::test::test_pong_message_with_extension ... ok
test wire::tests::prop_filter ... ok
test wire::tests::prop_id ... ok
test wire::tests::prop_oid ... ok
test wire::tests::prop_pubkey ... ok
test wire::tests::prop_refs ... ok
test wire::tests::prop_signature ... ok
test wire::tests::prop_signed_refs ... ok
test wire::tests::prop_string ... ok
test wire::tests::prop_tuple ... ok
test wire::tests::prop_u16 ... ok
test wire::tests::prop_u32 ... ok
test wire::tests::prop_u64 ... ok
test wire::tests::prop_u8 ... ok
test wire::tests::prop_vec ... ok
test wire::tests::test_alias ... ok
test wire::tests::test_bounded_vec_limit ... ok
test wire::tests::test_filter_invalid ... ok
test wire::tests::test_string ... ok
test wire::varint::test::prop_encode_decode ... ok
test wire::varint::test::test_encode_overflow - should panic ... ok
test wire::varint::test::test_encoding ... ok
test wire::message::tests::prop_message_decoder ... ok

failures:

---- tests::e2e::test_connection_crossing stdout ----
1758366581 [36mtest:[0m [36mNode::init alice: z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p[0m
1758366581 [36mtest:[0m [36mNode::init bob: z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU[0m
[2m1758366581 tests::e2e::test_connection_crossing      node: Opening policy database..[0m
[2m1758366581 tests::e2e::test_connection_crossing      node: Migrating COBs cache..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Migration of COBs cache complete (version=2)..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Default seeding policy set to 'block (all)'[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Initializing service (Test)..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Opening node database..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Address book is empty. Adding bootstrap nodes..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: 0 nodes added to address book[0m
[2m1758366582 tests::e2e::test_connection_crossing   service: Init @1758366581924[0m
[2m1758366582 tests::e2e::test_connection_crossing   service: Empty refs database, populating from storage..[0m
[2m1758366582 tests::e2e::test_connection_crossing   service: Not enough available peers to connect to (available=0, wanted=8)[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Binding control socket /tmp/.tmpSbw5ow/btAWWb0A/node/control.sock..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Opening policy database..[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <runtime>           node: Running node z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p in /tmp/.tmpSbw5ow/btAWWb0A..[0m
[33m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <signals>           node: Signal notifications channel error: receiving on an empty and disconnected channel[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           node: Listening on 0.0.0.0:42959..[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <control>        control: Control thread listening on socket..[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command QueryState(..)[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Migrating COBs cache..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Migration of COBs cache complete (version=2)..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Default seeding policy set to 'block (all)'[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Initializing service (Test)..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Opening node database..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Address book is empty. Adding bootstrap nodes..[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: 0 nodes added to address book[0m
[2m1758366582 tests::e2e::test_connection_crossing   service: Init @1758366582070[0m
[2m1758366582 tests::e2e::test_connection_crossing   service: Empty refs database, populating from storage..[0m
[2m1758366582 tests::e2e::test_connection_crossing   service: Not enough available peers to connect to (available=0, wanted=8)[0m
[2m1758366582 tests::e2e::test_connection_crossing      node: Binding control socket /tmp/.tmpSbw5ow/2VZcJg8H/node/control.sock..[0m
1758366582 [36mtest:[0m [36mPreferred peer is z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command QueryState(..)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command Connect(z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU, 0.0.0.0:46567, ConnectOptions { persistent: false, timeout: 30s })[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Connecting to z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (0.0.0.0:46567)..[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Attempted connection to z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (0.0.0.0:46567)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Registering outbound transport for z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (fd=27)..[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Outbound peer resource registered for z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU with id=2 (fd=27)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           node: Listening on 0.0.0.0:46567..[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           wire: Inbound connection from 127.0.0.1:45906 (fd=95)..[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           wire: Accepted inbound connection from 127.0.0.1:45906 (fd=95)..[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           wire: Inbound peer resource registered with id=2 (fd=95)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <runtime>           node: Running node z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU in /tmp/.tmpSbw5ow/2VZcJg8H..[0m
[33m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <signals>           node: Signal notifications channel error: receiving on an empty and disconnected channel[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <control>        control: Control thread listening on socket..[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Session established with z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (id=2) (fd=27) (outbound)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Connected to z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (0.0.0.0:46567) (Outbound)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Subscribing to messages since timestamp 1758280182214..[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           wire: Session established with z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p (id=2) (fd=95) (inbound)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Connected to z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p (127.0.0.1:45906) (Inbound)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Subscribing to messages since timestamp 1758280182216..[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received command QueryState(..)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received command QueryState(..)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received node announcement of z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU with 0 address(es) from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (t=1758366582070)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received node announcement of z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p with 0 address(es) from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p (t=1758366581924)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Stored announcement from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU to be broadcast in 0 millisecond(s) (t=1758366582070)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Stored announcement from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p to be broadcast in 0 millisecond(s) (t=1758366581924)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Address store entry for node z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU updated at 1758366582070[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received inventory announcement of z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU with 0 item(s) from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (t=1758366582071)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Address store entry for node z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p updated at 1758366581924[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received inventory announcement of z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p with 0 item(s) from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p (t=1758366581925)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Stored announcement from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU to be broadcast in 0 millisecond(s) (t=1758366582071)[0m
[2m1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received subscription filter from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Stored announcement from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p to be broadcast in 0 millisecond(s) (t=1758366581925)[0m
[2m1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received subscription filter from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command QueryState(..)[0m
[2m1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received command QueryState(..)[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command QueryState(..)[0m
1758366583 [36mtest:[0m [36mOk([Session { nid: PublicKey(z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU), link: Outbound, addr: Address(NetAddr { host: Ip(0.0.0.0), port: 46567 }), state: Connected { since: LocalTime { millis: 1758366582214 }, ping: None, fetching: {}, latencies: [], stable: false } }])[0m
[2m1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received command QueryState(..)[0m
1758366583 [36mtest:[0m [36mOk([Session { nid: PublicKey(z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p), link: Inbound, addr: Address(NetAddr { host: Ip(127.0.0.1), port: 45906 }), state: Connected { since: LocalTime { millis: 1758366582216 }, ping: None, fetching: {}, latencies: [], stable: false } }])[0m

thread 'tests::e2e::test_connection_crossing' panicked at crates/radicle-node/src/tests/e2e.rs:859:9:
assertion `left == right` failed
  left: Outbound
 right: Inbound
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
1758366583 [36mtest:[0m [36mNode z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU shutting down..[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Disconnecting peer with id=2: connection reset[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Transport handover for disconnecting peer with id=2 (fd=27)[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Disconnected from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (connection reset)[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Dropping peer z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU..[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Not enough available peers to connect to (available=0, wanted=8)[0m
[2m1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <control>        control: Received `{"command":"shutdown"}` on control socket[0m
[2m1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <control>        control: Shutdown requested..[0m
[2m1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <runtime>           pool: Worker pool shutting down..[0m
[2m1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <runtime>           node: Node shutdown completed for z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU[0m
1758366583 [36mtest:[0m [36mNode z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p shutting down..[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <control>        control: Received `{"command":"shutdown"}` on control socket[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <control>        control: Shutdown requested..[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <runtime>           pool: Worker pool shutting down..[0m
[2m1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <runtime>           node: Node shutdown completed for z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p[0m


failures:
    tests::e2e::test_connection_crossing

test result: FAILED. 112 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 104.91s

error: test failed, to rerun pass `-p radicle-node --lib`
ERROR: Action failed: command failed: ["cargo", "test", "--offline", "--locked", "--workspace"]
ERROR: command failed: ["cargo", "test", "--offline", "--locked", "--workspace"]
EXIT CODE: 1
[2025-09-20T11:11:26Z INFO  ambient] ambient ends successfully

Trigger message

{
  "request": "trigger",
  "version": 1,
  "event_type": "push",
  "repository": {
    "id": "rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5",
    "name": "heartwood",
    "description": "Radicle Heartwood Protocol & Stack",
    "private": false,
    "default_branch": "master",
    "delegates": [
      "did:key:z6MksFqXN3Yhqk8pTJdUGLwATkRfQvwZXPqR2qMEhbS9wzpT",
      "did:key:z6MktaNvN1KVFMkSRAiN4qK5yvX1zuEEaseeX5sffhzPZRZW",
      "did:key:z6MkireRatUThvd3qzfKht1S44wpm4FEWSSa4PRMTSQZ3voM",
      "did:key:z6MkgFq6z5fkF2hioLLSNu1zP2qEL1aHXHZzGH1FLFGAnBGz",
      "did:key:z6MkkPvBfjP4bQmco5Dm7UGsX2ruDBieEHi8n9DVJWX5sTEz"
    ]
  },
  "pusher": {
    "id": "did:key:z6Mku8hpprWTmCv3BqkssCYDfr2feUdyLSUnycVajFo9XVAx",
    "alias": "levitte"
  },
  "before": "1b958a1d86d54f7b97368a48c55f646ec81c5f64",
  "after": "1b958a1d86d54f7b97368a48c55f646ec81c5f64",
  "branch": "master",
  "commits": [
    "1b958a1d86d54f7b97368a48c55f646ec81c5f64"
  ]
}

Ambient stdout

[2025-09-20T11:04:42Z INFO  ambient] ambient starts
[2025-09-20T11:04:42Z 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-09-20T11:04:42Z 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-09-20T11:04:42Z DEBUG ambient] 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-09-20T11:04:42Z DEBUG ambient_ci::project] load project state from /home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/meta.yaml
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] latest commit: "6a1147f0555e7c3c751eeeb472a9d4caedf7afc9"
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] is a git repository
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] git repository is clean
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] current (HEAD) commit: 1b958a1d86d54f7b97368a48c55f646ec81c5f64
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] no dry run requested
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] forced run requested
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] run? true
[2025-09-20T11:04:42Z INFO  ambient_ci::run] project rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5: running CI
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-09-20T11:04:42Z DEBUG ambient_ci::action] Plan::execute: CargoFetch {
        sourcedir: "/tmp/.tmppOWYB6/src",
        dependenciesdir: "/home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/dependencies",
    }
SPAWN: argv=["env", "CARGO_HOME=/home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/dependencies", "cargo", "fetch", "--locked"]
       cwd=/tmp/.tmppOWYB6/src (exists? true)
       extra_env=[]
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] create virtual drive /home/_rad/tmp/.tmpjdta1i/src.tar
[2025-09-20T11:04:42Z DEBUG ambient_ci::run] create virtual drive /home/_rad/tmp/.tmpjdta1i/deps.tar
[2025-09-20T11:04:43Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-09-20T11:04:43Z 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,
            CargoTest,
            Shell {
                shell: "# Because of a (temporary) limitation in Ambient, we need to set\n# these variables manually. Once Ambient manages environment\n# variables better, these can be deleted.\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:$PATH\"\n\ncargo doc --workspace --no-deps\n",
            },
            Shell {
                shell: "# Because of a (temporary) limitation in Ambient, we need to set\n# these variables manually. Once Ambient manages environment\n# variables better, these can be deleted.\nexport CARGO_TARGET_DIR=/workspace/cache\nexport CARGO_HOME=/workspace/deps\nexport HOME=/root\nexport PATH=\"/root/.cargo/bin:$PATH\"\n\n# These are based on debian/control.\nexport DEBEMAIL=liw@liw.fi\nexport DEBFULLNAME=\"Lars Wirzenius\"\n\n# Clean up after tests and documentation building. The Debian\n# package building tools do not want changes outside the\n# `debian` directory, compared to what is committed to Git, from\n# which the \"upstream tarball\" is created.\ngit reset --hard\ngit clean -fdx\ngit status --ignored\n\n# Update debian/changelog with a new version so that every run\n# creates a newer version. This avoids us having to update the\n# file manually for every CI run.\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-09-20T11:04:43Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-09-20T11:04:43Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /home/_rad/tmp/.tmpjdta1i/ambient-execute-plan/run-ci
[2025-09-20T11:04:43Z DEBUG ambient_ci::run] copy OK: true
[2025-09-20T11:04:43Z DEBUG ambient_ci::run] create virtual drive /home/_rad/tmp/.tmpjdta1i/executor.tar
[2025-09-20T11:05:03Z DEBUG ambient_ci::project] removing run log file /home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-09-20T11:05:03Z DEBUG ambient_ci::project] statedir is /home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5, exists? true
[2025-09-20T11:05:03Z DEBUG ambient_ci::project] creating run log file /home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-09-20T11:05:03Z DEBUG ambient_ci::project] created run log file /home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log OK
[2025-09-20T11:05:03Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-09-20T11:05:03Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-09-20T11:05:03Z DEBUG ambient_ci::cloud_init] with_runcmd called: "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n"
[2025-09-20T11:05:03Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-09-20T11:05:03Z DEBUG ambient_ci::cloud_init] LocalDataStoreBuilder: LocalDataStoreBuilder {
        hostname: Some(
            "ambient",
        ),
        network: false,
        bootcmd: [],
        runcmd: [
            "echo xyzzy > /dev/ttyS1",
            "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n",
            "poweroff",
        ],
    }
[2025-09-20T11:05:03Z DEBUG ambient_ci::qemu] run QEMU
[2025-09-20T11:05:03Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-09-20T11:05:03Z DEBUG ambient_ci::qemu_utils] qemu-img create /home/_rad/tmp/.tmp0IJ0Qs/.tmpHX7HPI/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-09-20T11:05:03Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-09-20T11:05:03Z DEBUG ambient_ci::cloud_init] LocalDataStore: LocalDataStore {
        hostname: "ambient",
        network: false,
        bootcmd: [],
        runcmd: [
            "echo xyzzy > /dev/ttyS1",
            "\n(set -xeu\nenv\ndir=\"$(mktemp -d)\"\ncd \"$dir\"\ntar -xvf /dev/vdb\nfind -ls || true\nldd ./run-ci || true\necho ================================ BEGIN ================================\nexport RUST_BACKTRACE=1\nif ./run-ci; then\n        echo \"EXIT CODE: 0\"\nelse\n        echo \"EXIT CODE: $?\"\nfi) > /dev/ttyS1 2>&1\n",
            "poweroff",
        ],
    }
[2025-09-20T11:05:03Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpqPJI9O/meta-data
[2025-09-20T11:05:03Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpqPJI9O/user-data
[2025-09-20T11:05:03Z DEBUG ambient_ci::qemu] set console log file to /home/_rad/tmp/.tmp0IJ0Qs/.tmpHX7HPI/console.log
[2025-09-20T11:05:03Z DEBUG ambient_ci::util] create file /home/_rad/tmp/.tmp0IJ0Qs/.tmpHX7HPI/console.log
[2025-09-20T11:05:03Z DEBUG ambient_ci::qemu] set run file to /home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-09-20T11:05:03Z DEBUG ambient_ci::util] create file /home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-09-20T11:05:03Z 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/.tmp0IJ0Qs/.tmpHX7HPI/console.log",
            "-serial",
            "file:/home/_rad/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/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/.tmp0IJ0Qs/.tmpHX7HPI/vars.fd",
            "-drive",
            "format=qcow2,if=virtio,file=/home/_rad/tmp/.tmp0IJ0Qs/.tmpHX7HPI/vm.qcow2",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpjdta1i/executor.tar,readonly=on",
            "-cdrom",
            "/home/_rad/tmp/.tmp0IJ0Qs/.tmpHX7HPI/cloud_init.iso",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpjdta1i/src.tar,readonly=on",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpjdta1i/artifacts.tar",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpjdta1i/cache.tar",
            "-drive",
            "format=raw,if=virtio,file=/home/_rad/tmp/.tmpjdta1i/deps.tar,readonly=on",
            "-nodefaults",
            "-no-user-config",
        ],
    }
[2025-09-20T11:11:20Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-09-20T11:11:20Z DEBUG ambient_ci::qemu] run log:
    --------------------==========
    [2025-09-20T11:05:07Z TRACE ambient_execute_plan] ambient-execute-plan starts
    [2025-09-20T11:05:07Z DEBUG ambient_execute_plan] ambient-execute-plan starts
    [2025-09-20T11:05:07Z INFO  ambient_execute_plan] ambient-execute-plan starts
    [2025-09-20T11:05:07Z WARN  ambient_execute_plan] ambient-execute-plan starts
    [2025-09-20T11:05:07Z 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: cargo_test
    - action: shell
      shell: |
        # Because of a (temporary) limitation in Ambient, we need to set
        # these variables manually. Once Ambient manages environment
        # variables better, these can be deleted.
        export CARGO_TARGET_DIR=/workspace/cache
        export CARGO_HOME=/workspace/deps
        export HOME=/root
        export PATH="/root/.cargo/bin:$PATH"
    
        cargo doc --workspace --no-deps
    - action: shell
      shell: |
        # Because of a (temporary) limitation in Ambient, we need to set
        # these variables manually. Once Ambient manages environment
        # variables better, these can be deleted.
        export CARGO_TARGET_DIR=/workspace/cache
        export CARGO_HOME=/workspace/deps
        export HOME=/root
        export PATH="/root/.cargo/bin:$PATH"
    
        # These are based on debian/control.
        export DEBEMAIL=liw@liw.fi
        export DEBFULLNAME="Lars Wirzenius"
    
        # Clean up after tests and documentation building. The Debian
        # package building tools do not want changes outside the
        # `debian` directory, compared to what is committed to Git, from
        # which the "upstream tarball" is created.
        git reset --hard
        git clean -fdx
        git status --ignored
    
        # Update debian/changelog with a new version so that every run
        # creates a newer version. This avoids us having to update the
        # file manually for every CI run.
        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-09-20T11:05:07Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace",
        }
    RUN: Action finished OK
    RUN: Action Mkdir {
        pathname: "/workspace/artifacts",
    }
    [2025-09-20T11:05:07Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
            pathname: "/workspace/artifacts",
        }
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdc",
        directory: "/workspace/src",
    }
    [2025-09-20T11:05:07Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdc",
            directory: "/workspace/src",
        }
    [2025-09-20T11:05:07Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
    [2025-09-20T11:05:07Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vdf",
        directory: "/workspace/deps",
    }
    [2025-09-20T11:05:07Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vdf",
            directory: "/workspace/deps",
        }
    [2025-09-20T11:05:07Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
    [2025-09-20T11:05:11Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action TarExtract {
        archive: "/dev/vde",
        directory: "/workspace/cache",
    }
    [2025-09-20T11:05:11Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
            archive: "/dev/vde",
            directory: "/workspace/cache",
        }
    [2025-09-20T11:05:11Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
    [2025-09-20T11:05:57Z TRACE ambient_ci::vdrive] extraction OK
    RUN: Action finished OK
    RUN: Action Spawn {
        argv: [
            "find",
            "/workspace",
            "-maxdepth",
            "2",
            "-ls",
        ],
    }
    [2025-09-20T11:05:57Z 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=[]
      2621441      4 drwxr-xr-x   6 root     root         4096 Sep 20 11:05 /workspace
      2621442      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:05 /workspace/artifacts
      2753993      4 drwxr-xr-x   6 root     root         4096 Sep 19 13:49 /workspace/cache
      2764295      4 drwxr-xr-x  30 root     root         4096 Sep 20 09:48 /workspace/cache/doc
      2769493      4 drwxr-xr-x   7 root     root         4096 Sep 20 09:44 /workspace/cache/debug
      2753994      4 -rw-r--r--   1 root     root         1032 Sep 20 09:44 /workspace/cache/.rustc_info.json
      2753995      4 drwxr-xr-x   7 root     root         4096 Sep 20 09:51 /workspace/cache/release
      2769491      4 drwxr-xr-x   2 root     root         4096 Sep 19 13:39 /workspace/cache/tmp
      2769492      4 -rw-r--r--   1 root     root          218 Sep 20 09:03 /workspace/cache/.rustdoc_fingerprint.json
      2623225      4 drwxr-xr-x   3 root     root         4096 Sep 20 11:04 /workspace/deps
      2669918      0 -rw-r--r--   1 root     root            0 Sep 10 13:40 /workspace/deps/.package-cache
      2623226      4 drwxr-xr-x   5 root     root         4096 Sep 10 13:40 /workspace/deps/registry
      2669916    180 -rw-r--r--   1 root     root       184320 Sep 20 11:04 /workspace/deps/.global-cache
      2669917      0 -rw-r--r--   1 root     root            0 Sep 10 13:40 /workspace/deps/.package-cache-mutate
      2621443      4 drwxr-xr-x  12 root     root         4096 Sep 20 11:04 /workspace/src
      2622030     12 -rw-r--r--   1 root     root         9723 Sep 20 11:04 /workspace/src/LICENSE-APACHE
      2621448      8 -rw-r--r--   1 root     root         5666 Sep 20 11:04 /workspace/src/rad-id.1.adoc
      2621445      4 -rw-r--r--   1 root     root           77 Sep 20 11:04 /workspace/src/rust-toolchain.toml
      2621450      8 -rw-r--r--   1 root     root         5102 Sep 20 11:04 /workspace/src/flake.lock
      2621456      4 drwxr-xr-x   5 root     root         4096 Sep 20 11:04 /workspace/src/systemd
      2622037      4 -rw-r--r--   1 root     root         1083 Sep 20 11:04 /workspace/src/.gitsigners
      2622039      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/.github
      2622034      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/.radicle
      2622032      4 -rw-r--r--   1 root     root         1203 Sep 20 11:04 /workspace/src/DCO
      2621452      4 -rw-r--r--   1 root     root         2397 Sep 20 11:04 /workspace/src/Cargo.toml
      2621446      8 -rw-r--r--   1 root     root         8022 Sep 20 11:04 /workspace/src/rad.1.adoc
      2621476      8 -rw-r--r--   1 root     root         5432 Sep 20 11:04 /workspace/src/deny.toml
      2621454     12 -rw-r--r--   1 root     root         8322 Sep 20 11:04 /workspace/src/CONTRIBUTING.md
      2621451      4 -rw-r--r--   1 root     root         3091 Sep 20 11:04 /workspace/src/README.md
      2622042      4 -rw-r--r--   1 root     root           61 Sep 20 11:04 /workspace/src/.env.seed
      2621475      4 -rw-r--r--   1 root     root          571 Sep 20 11:04 /workspace/src/git-remote-rad.1.adoc
      2621447     12 -rw-r--r--   1 root     root        10770 Sep 20 11:04 /workspace/src/rad-patch.1.adoc
      2622041      4 -rw-r--r--   1 root     root           42 Sep 20 11:04 /workspace/src/.envrc
      2622038      4 -rw-r--r--   1 root     root          101 Sep 20 11:04 /workspace/src/.gitignore
      2621449     12 -rw-r--r--   1 root     root         8803 Sep 20 11:04 /workspace/src/flake.nix
      2622027      4 -rw-r--r--   1 root     root         1623 Sep 20 11:04 /workspace/src/build.rs
      2622028      4 -rw-r--r--   1 root     root         2163 Sep 20 11:04 /workspace/src/VERSIONING.md
      2622033      4 -rw-r--r--   1 root     root           79 Sep 20 11:04 /workspace/src/ARCHITECTURE.md
      2621455      8 -rw-r--r--   1 root     root         4304 Sep 20 11:04 /workspace/src/CHANGELOG.md
      2622044      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/.config
      2621453     96 -rw-r--r--   1 root     root        95685 Sep 20 11:04 /workspace/src/Cargo.lock
      2621477      4 drwxr-xr-x   3 root     root         4096 Sep 20 11:04 /workspace/src/debian
      2622048      4 drwxr-xr-x   8 root     root         4096 Sep 20 11:04 /workspace/src/.git
      2622031      8 -rw-r--r--   1 root     root         7645 Sep 20 11:04 /workspace/src/HACKING.md
      2622029      4 -rw-r--r--   1 root     root         1079 Sep 20 11:04 /workspace/src/LICENSE-MIT
      2621474      4 -rw-r--r--   1 root     root          426 Sep 20 11:04 /workspace/src/radicle-node.1.adoc
      2622014      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/build
      2621444      0 -rw-r--r--   1 root     root            0 Sep 20 11:04 /workspace/src/test
      2621463      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/scripts
      2622046      4 drwxr-xr-x   2 root     root         4096 Sep 20 11:04 /workspace/src/.cargo
      2621490      4 drwxr-xr-x  16 root     root         4096 Sep 20 11:04 /workspace/src/crates
      2622043      4 -rw-r--r--   1 root     root           30 Sep 20 11:04 /workspace/src/.dockerignore
    RUN: Action finished OK
    RUN: Action CargoFmt
    [2025-09-20T11:05:57Z 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-09-20T11:05:58Z 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")]
        Checking radicle-ssh v0.9.0 (/workspace/src/crates/radicle-ssh)
        Checking radicle-dag v0.10.0 (/workspace/src/crates/radicle-dag)
        Checking radicle-signals v0.11.0 (/workspace/src/crates/radicle-signals)
        Checking radicle-crypto v0.12.0 (/workspace/src/crates/radicle-crypto)
       Compiling radicle-cli v0.13.0 (/workspace/src/crates/radicle-cli)
        Checking radicle-cob v0.14.0 (/workspace/src/crates/radicle-cob)
        Checking radicle-term v0.12.0 (/workspace/src/crates/radicle-term)
       Compiling radicle-node v0.11.0 (/workspace/src/crates/radicle-node)
        Checking radicle v0.15.0 (/workspace/src/crates/radicle)
        Checking radicle-systemd v0.9.0 (/workspace/src/crates/radicle-systemd)
       Compiling radicle-remote-helper v0.10.0 (/workspace/src/crates/radicle-remote-helper)
        Checking radicle-fetch v0.11.0 (/workspace/src/crates/radicle-fetch)
        Checking radicle-cli-test v0.11.0 (/workspace/src/crates/radicle-cli-test)
        Checking radicle-schemars v0.1.0 (/workspace/src/crates/radicle-schemars)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 14.50s
    RUN: Action finished OK
    RUN: Action CargoBuild
    [2025-09-20T11:06:13Z 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 radicle-ssh v0.9.0 (/workspace/src/crates/radicle-ssh)
       Compiling radicle-dag v0.10.0 (/workspace/src/crates/radicle-dag)
       Compiling radicle-crypto v0.12.0 (/workspace/src/crates/radicle-crypto)
       Compiling radicle-signals v0.11.0 (/workspace/src/crates/radicle-signals)
       Compiling radicle-cob v0.14.0 (/workspace/src/crates/radicle-cob)
       Compiling radicle-cli v0.13.0 (/workspace/src/crates/radicle-cli)
       Compiling radicle v0.15.0 (/workspace/src/crates/radicle)
       Compiling radicle-term v0.12.0 (/workspace/src/crates/radicle-term)
       Compiling radicle-node v0.11.0 (/workspace/src/crates/radicle-node)
       Compiling radicle-systemd v0.9.0 (/workspace/src/crates/radicle-systemd)
       Compiling radicle-remote-helper v0.10.0 (/workspace/src/crates/radicle-remote-helper)
       Compiling radicle-fetch v0.11.0 (/workspace/src/crates/radicle-fetch)
       Compiling radicle-cli-test v0.11.0 (/workspace/src/crates/radicle-cli-test)
       Compiling radicle-schemars v0.1.0 (/workspace/src/crates/radicle-schemars)
        Finished `dev` profile [unoptimized + debuginfo] target(s) in 48.08s
    RUN: Action finished OK
    RUN: Action CargoTest
    [2025-09-20T11:07:01Z DEBUG ambient_ci::action] Plan::execute: CargoTest
    SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
           cwd=/workspace/src (exists? true)
           extra_env=[("CARGO_TARGET_DIR", "/workspace/cache"), ("CARGO_HOME", "/workspace/deps"), ("PATH", "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin")]
        Finished `test` profile [unoptimized + debuginfo] target(s) in 0.49s
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle-b4bf1c275801b632)
    
    running 188 tests
    test canonical::formatter::test::ascii_control_characters ... ok
    test canonical::formatter::test::securesystemslib_asserts ... ok
    test canonical::formatter::test::ordered_nested_object ... ok
    test cob::cache::migrations::_2::tests::test_migration_2 ... ok
    test cob::cache::tests::test_check_version ... ok
    test cob::cache::tests::test_migrate_to ... ok
    test cob::cache::migrations::_2::tests::test_patch_json_deserialization ... ok
    test cob::common::test::test_color ... ok
    test cob::common::test::test_emojis ... ok
    test cob::identity::test::prop_json_eq_str ... ok
    test cob::identity::test::test_identity_redact_revision ... ok
    test cob::identity::test::test_identity_reject_concurrent ... ok
    test cob::identity::test::test_identity_remove_delegate_concurrent ... ok
    test cob::identity::test::test_identity_update_rejected ... ok
    test cob::identity::test::test_identity_updates ... ok
    test cob::identity::test::test_identity_updates_concurrent ... ok
    test cob::identity::test::test_identity_updates_concurrent_outdated ... ok
    test cob::issue::cache::tests::test_counts ... ok
    test cob::issue::cache::tests::test_get ... ok
    test cob::issue::cache::tests::test_is_empty ... ok
    test cob::issue::cache::tests::test_list ... ok
    test cob::issue::cache::tests::test_list_by_status ... ok
    test cob::issue::cache::tests::test_remove ... ok
    test cob::identity::test::test_valid_identity ... ok
    test cob::issue::test::test_embeds ... ok
    test cob::issue::test::test_embeds_edit ... ok
    test cob::issue::test::test_invalid_actions ... ok
    test cob::issue::test::test_concurrency ... ok
    test cob::issue::test::test_invalid_cob ... ok
    test cob::issue::test::test_invalid_tx ... ok
    test cob::issue::test::test_invalid_tx_reference ... ok
    test cob::issue::test::test_issue_all ... ok
    test cob::issue::test::test_issue_comment ... ok
    test cob::issue::test::test_issue_comment_redact ... ok
    test cob::issue::test::test_issue_create_and_assign ... ok
    test cob::issue::test::test_issue_create_and_get ... ok
    test cob::issue::test::test_issue_create_and_change_state ... ok
    test cob::issue::test::test_issue_create_and_unassign ... ok
    test cob::issue::test::test_issue_create_and_reassign ... ok
    test cob::issue::test::test_issue_edit ... ok
    test cob::issue::test::test_issue_edit_description ... ok
    test cob::issue::test::test_issue_label ... ok
    test cob::issue::test::test_issue_multilines ... ok
    test cob::issue::test::test_issue_react ... ok
    test cob::issue::test::test_issue_state_serde ... ok
    test cob::issue::test::test_ordering ... ok
    test cob::issue::test::test_issue_reply ... ok
    test cob::patch::cache::tests::test_counts ... ok
    test cob::patch::cache::tests::test_get ... ok
    test cob::patch::cache::tests::test_is_empty ... ok
    test cob::patch::cache::tests::test_list ... ok
    test cob::patch::cache::tests::test_list_by_status ... ok
    test cob::patch::cache::tests::test_remove ... ok
    test cob::patch::test::test_json ... ok
    test cob::patch::test::test_json_serialization ... ok
    test cob::patch::test::test_patch_create_and_get ... ok
    test cob::patch::test::test_patch_discussion ... ok
    test cob::patch::test::test_patch_merge ... ok
    test cob::patch::test::test_patch_redact ... ok
    test cob::patch::test::test_patch_review ... ok
    test cob::patch::test::test_patch_review_comment ... ok
    test cob::patch::test::test_patch_review_duplicate ... ok
    test cob::patch::test::test_patch_review_edit ... ok
    test cob::patch::test::test_patch_review_edit_comment ... ok
    test cob::patch::test::test_patch_review_remove_summary ... ok
    test cob::patch::cache::tests::test_find_by_revision ... ok
    test cob::patch::test::test_patch_review_revision_redact ... ok
    test cob::patch::test::test_reactions_json_serialization ... ok
    test cob::patch::test::test_revision_edit_redact ... ok
    test cob::patch::test::test_revision_reaction ... ok
    test cob::patch::test::test_revision_review_merge_redacted ... ok
    test cob::patch::test::test_patch_update ... ok
    test cob::thread::tests::test_comment_edit_missing ... ok
    test cob::thread::tests::test_comment_edit_redacted ... ok
    test cob::thread::tests::test_comment_redact_missing ... ok
    test cob::thread::tests::test_duplicate_comments ... ok
    test cob::thread::tests::test_edit_comment ... ok
    test cob::thread::tests::test_redact_comment ... ok
    test cob::thread::tests::test_timeline ... ok
    test git::canonical::tests::test_quorum ... ok
    test git::canonical::tests::test_quorum_merges ... ok
    test git::canonical::tests::test_quorum_properties ... ok
    test git::test::test_version_from_str ... ok
    test git::test::test_version_ord ... ok
    test identity::did::test::test_did_encode_decode ... ok
    test identity::did::test::test_did_vectors ... ok
    test identity::doc::id::test::prop_from_str ... ok
    test cob::thread::tests::prop_ordering ... ok
    test identity::doc::test::test_canonical_doc ... ok
    test identity::doc::test::test_canonical_example ... ok
    test identity::doc::test::test_duplicate_dids ... ok
    test identity::doc::test::test_future_version_error ... ok
    test identity::doc::test::test_is_valid_version ... ok
    test identity::doc::test::test_max_delegates ... ok
    test identity::doc::test::test_not_found ... ok
    test identity::doc::test::test_parse_version ... ok
    test identity::doc::test::test_visibility_json ... ok
    test identity::project::test::test_project_name ... ok
    test node::address::store::test::test_alias ... ok
    test node::address::store::test::test_disconnected ... ok
    test node::address::store::test::test_disconnected_ban ... ok
    test node::address::store::test::test_empty ... ok
    test node::address::store::test::test_entries ... ok
    test node::address::store::test::test_get_none ... ok
    test node::address::store::test::test_insert_and_get ... ok
    test node::address::store::test::test_insert_and_remove ... ok
    test node::address::store::test::test_insert_and_update ... ok
    test node::address::store::test::test_insert_duplicate ... ok
    test node::address::store::test::test_node_aliases ... ok
    test node::address::store::test::test_remove_nothing ... ok
    test node::db::test::test_version ... ok
    test node::features::test::test_operations ... ok
    test node::notifications::store::test::test_branch_notifications ... ok
    test node::notifications::store::test::test_clear ... ok
    test node::notifications::store::test::test_cob_notifications ... ok
    test node::notifications::store::test::test_counts_by_repo ... ok
    test node::notifications::store::test::test_duplicate_notifications ... ok
    test node::notifications::store::test::test_notification_status ... ok
    test node::policy::store::test::test_follow_and_unfollow_node ... ok
    test node::policy::store::test::test_node_aliases ... ok
    test node::policy::store::test::test_node_policies ... ok
    test node::policy::store::test::test_node_policy ... ok
    test node::policy::store::test::test_repo_policies ... ok
    test node::policy::store::test::test_repo_policy ... ok
    test node::policy::store::test::test_seed_and_unseed_repo ... ok
    test node::policy::store::test::test_update_alias ... ok
    test node::policy::store::test::test_update_scope ... ok
    test node::refs::store::test::test_count ... ok
    test node::refs::store::test::test_set_and_delete ... ok
    test node::refs::store::test::test_set_and_get ... ok
    test node::routing::test::test_count ... ok
    test node::routing::test::test_entries ... ok
    test node::routing::test::test_insert_and_get ... ok
    test node::routing::test::test_insert_and_get_resources ... ok
    test node::routing::test::test_insert_and_remove ... ok
    test node::routing::test::test_insert_duplicate ... ok
    test node::routing::test::test_insert_existing_updated_time ... ok
    test node::routing::test::test_len ... ok
    test node::routing::test::test_prune ... ok
    test node::routing::test::test_remove_many ... ok
    test node::routing::test::test_remove_redundant ... ok
    test node::routing::test::test_update_existing_multi ... ok
    test node::sync::announce::test::announcer_must_reach_preferred_seeds ... ok
    test node::sync::announce::test::announcer_reached_max_replication_target ... ok
    test node::sync::announce::test::announcer_reached_min_replication_target ... ok
    test node::sync::announce::test::announcer_timed_out ... ok
    test node::sync::announce::test::announcer_will_minimise_replication_factor ... ok
    test node::sync::announce::test::cannot_construct_announcer ... ok
    test node::sync::fetch::test::all_nodes_are_candidates ... ok
    test node::sync::fetch::test::all_nodes_are_fetchable ... ok
    test node::sync::fetch::test::could_not_reach_target ... ok
    test node::sync::fetch::test::ignores_duplicates_and_local_node ... ok
    test node::sync::fetch::test::preferred_seeds_target_returned_over_replicas ... ok
    test node::sync::fetch::test::reaches_target_of_max_replicas ... ok
    test node::sync::fetch::test::reaches_target_of_preferred_seeds ... ok
    test node::sync::fetch::test::reaches_target_of_replicas ... ok
    test node::sync::test::ensure_replicas_construction ... ok
    test node::sync::test::replicas_constrain_to ... ok
    test node::test::test_alias ... ok
    test node::test::test_command_result ... ok
    test node::test::test_user_agent ... ok
    test node::timestamp::tests::test_timestamp_max ... ok
    test profile::config::test::schema ... ok
    test profile::test::canonicalize_home ... ok
    test profile::test::test_config ... ok
    test rad::tests::test_checkout ... ok
    test rad::tests::test_fork ... ok
    test rad::tests::test_init ... ok
    test serde_ext::test::test_localtime ... ok
    test serde_ext::test::test_localtime_ext ... ok
    test storage::git::tests::test_references_of ... ok
    test storage::git::tests::test_remote_refs ... ok
    test storage::git::tests::test_sign_refs ... ok
    test storage::git::transport::local::url::test::test_url_parse ... ok
    test storage::git::transport::local::url::test::test_url_to_string ... ok
    test storage::git::transport::remote::url::test::test_url_parse ... ok
    test storage::refs::tests::prop_canonical_roundtrip ... ok
    test storage::refs::tests::test_rid_verification ... ok
    test storage::tests::test_storage ... ok
    test test::assert::test::assert_with_message ... ok
    test test::assert::test::test_assert_no_move ... ok
    test test::assert::test::test_assert_panic_0 - should panic ... ok
    test test::assert::test::test_assert_panic_1 - should panic ... ok
    test test::assert::test::test_assert_panic_2 - should panic ... ok
    test test::assert::test::test_assert_succeed ... ok
    test test::assert::test::test_panic_message ... ok
    test version::test::test_version ... ok
    test identity::doc::test::prop_encode_decode ... ok
    
    test result: ok. 188 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.96s
    
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cli-c4082d6a1fa8a293)
    
    running 14 tests
    test commands::rad_patch::review::builder::tests::test_review_comments_basic ... ok
    test commands::rad_patch::review::builder::tests::test_review_comments_before ... ok
    test commands::rad_patch::review::builder::tests::test_review_comments_multiline ... ok
    test commands::rad_patch::review::builder::tests::test_review_comments_split_hunk ... ok
    test git::ddiff::tests::diff_encode_decode_ddiff_hunk ... ok
    test git::pretty_diff::test::test_pretty ... ignored
    test git::unified_diff::test::test_diff_content_encode_decode_content ... ok
    test git::unified_diff::test::test_diff_encode_decode_diff ... ok
    test terminal::format::test::test_bytes ... ok
    test terminal::format::test::test_strip_comments ... ok
    test commands::rad_inspect::test::test_tree ... ok
    test terminal::patch::test::test_edit_display_message ... ok
    test terminal::patch::test::test_create_display_message ... ok
    test terminal::patch::test::test_update_display_message ... ok
    
    test result: ok. 13 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.01s
    
         Running unittests src/main.rs (/workspace/cache/debug/deps/rad-ada175d36f8264d9)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
         Running tests/commands.rs (/workspace/cache/debug/deps/commands-1e8a24d540410ee4)
    
    running 96 tests
    test framework_home ... ok
    test git_push_amend ... ok
    test git_push_and_fetch ... ok
    test git_push_diverge ... ok
    test git_push_converge ... ok
    test git_push_rollback ... ok
    test rad_auth ... ok
    test rad_auth_errors ... ok
    test rad_block ... ok
    test git_tag ... ok
    test rad_checkout ... ok
    test rad_clone ... ok
    test rad_clean ... ok
    test rad_clone_all ... ok
    test rad_clone_connect ... ok
    test rad_clone_directory ... ok
    test rad_clone_unknown ... ok
    test rad_clone_partial_fail ... ok
    test rad_cob_log ... ok
    test rad_cob_migrate ... ok
    test rad_cob_multiset ... ok
    test rad_cob_show ... ok
    test rad_cob_update ... ok
    test rad_cob_update_identity ... ok
    test rad_diff ... ok
    test rad_config ... ok
    test rad_fetch ... ok
    test rad_fork ... ok
    test rad_id_collaboration ... ignored, slow
    test rad_id ... ok
    test rad_id_conflict ... ok
    test rad_id_private ... ok
    test rad_id_multi_delegate ... ok
    test rad_id_threshold ... ok
    test rad_id_unknown_field ... ok
    test rad_id_threshold_soft_fork ... ok
    test rad_id_update_delete_field ... ok
    test rad_init ... ok
    test rad_init_existing ... ok
    test rad_init_no_git ... ok
    test rad_init_no_seed ... ok
    test rad_init_private ... ok
    test rad_inbox ... ok
    test rad_init_private_clone ... ok
    test rad_init_private_clone_seed ... ok
    test rad_init_private_no_seed ... ok
    test rad_init_private_seed ... ok
    test rad_init_sync_not_connected ... ok
    test rad_init_sync_preferred ... ok
    test rad_init_sync_and_clone ... ok
    test rad_init_with_existing_remote ... ok
    test rad_inspect ... ok
    test rad_issue ... ok
    test rad_merge_after_update ... ok
    test rad_init_sync_timeout ... ok
    test rad_merge_no_ff ... ok
    test rad_merge_via_push ... ok
    test rad_node_connect ... ok
    test rad_node ... ok
    test rad_patch_ahead_behind ... ok
    test rad_patch ... ok
    test rad_patch_change_base ... ok
    test rad_patch_checkout ... ok
    test rad_patch_checkout_revision ... ok
    test rad_patch_checkout_force ... ok
    test rad_patch_detached_head ... ok
    test rad_patch_diff ... ok
    test rad_patch_draft ... ok
    test rad_patch_edit ... ok
    test rad_patch_delete ... ok
    test rad_patch_fetch_2 ... ok
    test rad_patch_fetch_1 ... ok
    test rad_patch_merge_draft ... ok
    test rad_patch_open_explore ... ok
    test rad_patch_revert_merge ... ok
    test rad_patch_update ... ok
    test rad_patch_via_push ... ok
    test rad_publish ... ok
    test rad_patch_pull_update ... ok
    test rad_push_and_pull_patches ... ok
    test rad_review_by_hunk ... ok
    test rad_seed_and_follow ... ok
    test rad_remote ... ok
    test rad_self ... ok
    test rad_seed_many ... ok
    test rad_sync_without_node ... ok
    test rad_unseed ... ok
    test rad_sync ... ok
    test rad_warn_old_nodes ... ok
    test rad_unseed_many ... ok
    test rad_watch ... ok
    test test_clone_without_seeds ... ok
    test test_cob_deletion ... ok
    test rad_workflow ... ok
    test test_cob_replication ... ok
    test test_replication_via_seed ... ok
    
    test result: ok. 95 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 145.84s
    
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cli_test-354ae0a9d473645d)
    
    running 3 tests
    test tests::test_parse ... ok
    test tests::test_run ... ok
    test tests::test_example_spaced_brackets ... ok
    
    test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
    
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cob-e85912e820568959)
    
    running 8 tests
    test object::tests::test_serde ... ok
    test tests::invalid_parse_refstr ... ok
    test tests::parse_refstr ... ok
    test tests::roundtrip ... ok
    test tests::list_cobs ... ok
    test tests::update_cob ... ok
    test type_name::test::valid_typenames ... ok
    test tests::traverse_cobs ... ok
    
    test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
    
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_crypto-5617725847bbf5b6)
    
    running 12 tests
    test ssh::fmt::test::test_key ... ok
    test ssh::fmt::test::test_fingerprint ... ok
    test ssh::keystore::tests::test_init_no_passphrase ... ok
    test ssh::keystore::tests::test_signer ... ok
    test ssh::test::prop_encode_decode_sk ... ok
    test ssh::test::test_agent_encoding_remove ... ok
    test ssh::test::test_agent_encoding_sign ... ok
    test tests::prop_encode_decode ... ok
    test tests::prop_key_equality ... ok
    test tests::test_e25519_dh ... ok
    test tests::test_encode_decode ... ok
    test ssh::keystore::tests::test_init_passphrase ... ok
    
    test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.28s
    
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_dag-20ca1aca3d76c348)
    
    running 20 tests
    test tests::test_contains ... ok
    test tests::test_cycle ... ok
    test tests::test_dependencies ... ok
    test tests::test_diamond ... ok
    test tests::test_fold_diamond ... ok
    test tests::test_fold_multiple_roots ... ok
    test tests::test_fold_reject ... ok
    test tests::test_fold_sorting_1 ... ok
    test tests::test_fold_sorting_2 ... ok
    test tests::test_get ... ok
    test tests::test_is_empty ... ok
    test tests::test_len ... ok
    test tests::test_merge_1 ... ok
    test tests::test_merge_2 ... ok
    test tests::test_prune_1 ... ok
    test tests::test_prune_2 ... ok
    test tests::test_prune_by_sorting ... ok
    test tests::test_remove ... ok
    test tests::test_siblings ... ok
    test tests::test_complex ... ok
    
    test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
    
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_fetch-d2fb41089fc1c71d)
    
    running 0 tests
    
    test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
    
         Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_node-b756a84f7d576953)
    
    running 113 tests
    test control::tests::test_control_socket ... ok
    test deserializer::test::prop_decode_next ... ok
    test deserializer::test::test_decode_next ... ok
    test deserializer::test::test_unparsed ... ok
    test service::filter::test::test_parameters ... ok
    test control::tests::test_seed_unseed ... ok
    test service::filter::test::test_sizes ... ok
    test service::limiter::test::test_limitter_different_rates ... ok
    test service::limiter::test::test_limitter_multi ... ok
    test service::gossip::store::test::test_announced ... ok
    test service::limiter::test::test_limitter_refill ... ok
    test service::message::tests::test_inventory_limit ... ok
    test service::message::tests::prop_refs_announcement_signing ... ok
    test service::message::tests::test_ref_remote_limit ... ok
    test tests::e2e::missing_default_branch ... ok
    test service::message::tests::test_node_announcement_validate ... ok
    test tests::e2e::missing_delegate_default_branch ... ok
    test tests::e2e::test_background_foreground_fetch ... ok
    test tests::e2e::test_catchup_on_refs_announcements ... ok
    test tests::e2e::test_channel_reader_limit ... ok
    test tests::e2e::test_clone ... ok
    test tests::e2e::test_connection_crossing ... FAILED
    test tests::e2e::test_dont_fetch_owned_refs ... ok
    test tests::e2e::test_concurrent_fetches ... ok
    test tests::e2e::test_fetch_followed_remotes ... ok
    test tests::e2e::test_fetch_preserve_owned_refs ... ok
    test tests::e2e::test_fetch_unseeded ... ok
    test tests::e2e::test_fetch_up_to_date ... ok
    test tests::e2e::test_inventory_sync_basic ... ok
    test tests::e2e::test_inventory_sync_bridge ... ok
    test tests::e2e::test_inventory_sync_ring ... ok
    test tests::e2e::test_large_fetch ... ok
    test tests::e2e::test_migrated_clone ... ok
    test tests::e2e::test_missing_remote ... ok
    test tests::e2e::test_multiple_offline_inits ... ok
    test tests::e2e::test_inventory_sync_star ... ok
    test tests::e2e::test_non_fastforward_sigrefs ... ok
    test tests::e2e::test_outdated_delegate_sigrefs ... ok
    test tests::e2e::test_replication ... ok
    test tests::e2e::test_outdated_sigrefs ... ok
    test tests::e2e::test_replication_invalid ... ok
    test tests::e2e::test_replication_ref_in_sigrefs ... ok
    test tests::prop_inventory_exchange_dense ... ok
    test tests::test_announcement_rebroadcast ... ok
    test tests::test_announcement_rebroadcast_duplicates ... ok
    test tests::test_announcement_rebroadcast_timestamp_filtered ... ok
    test tests::test_announcement_message_amplification ... ok
    test tests::test_connection_kept_alive ... ok
    test tests::test_announcement_relay ... ok
    test tests::test_disconnecting_unresponsive_peer ... ok
    test tests::test_fetch_missing_inventory_on_gossip ... ok
    test tests::test_fetch_missing_inventory_on_schedule ... ok
    test tests::test_inbound_connection ... ok
    test tests::test_inventory_decode ... ok
    test tests::test_init_and_seed ... ok
    test tests::test_inventory_relay ... ok
    test tests::test_inventory_relay_bad_timestamp ... ok
    test tests::test_inventory_sync ... ok
    test tests::test_maintain_connections ... ok
    test tests::test_maintain_connections_failed_attempt ... ok
    test tests::test_maintain_connections_transient ... ok
    test tests::test_outbound_connection ... ok
    test tests::test_persistent_peer_connect ... ok
    test tests::test_inventory_pruning ... ok
    test tests::test_persistent_peer_reconnect_success ... ok
    test tests::test_persistent_peer_reconnect_attempt ... ok
    test tests::test_ping_response ... ok
    test tests::test_queued_fetch_from_ann_same_rid ... ok
    test tests::test_queued_fetch_from_command_same_rid ... ok
    test tests::test_queued_fetch_max_capacity ... ok
    test tests::test_redundant_connect ... ok
    test tests::test_refs_announcement_followed ... ok
    test tests::test_refs_announcement_fetch_trusted_no_inventory ... ok
    test tests::test_refs_announcement_no_subscribe ... ok
    test tests::test_refs_announcement_offline ... ok
    test tests::test_refs_announcement_relay_private ... ok
    test tests::test_refs_announcement_relay_public ... ok
    test tests::test_refs_synced_event ... ok
    test tests::test_seed_repo_subscribe ... ok
    test wire::frame::test::test_stream_id ... ok
    test tests::test_seeding ... ok
    test wire::message::tests::prop_addr ... ok
    test wire::message::tests::prop_message_encode_decode ... ok
    test wire::message::tests::prop_zero_bytes_encode_decode ... ok
    test wire::message::tests::test_inv_ann_max_size ... ok
    test wire::message::tests::test_node_ann_max_size ... ok
    test wire::message::tests::test_pingpong_encode_max_size ... ok
    test wire::message::tests::test_pingpong_encode_size_overflow ... ok
    test wire::message::tests::test_refs_ann_max_size ... ok
    test wire::protocol::test::test_inventory_ann_with_extension ... ok
    test wire::protocol::test::test_pong_message_with_extension ... ok
    test wire::tests::prop_filter ... ok
    test wire::tests::prop_id ... ok
    test wire::tests::prop_oid ... ok
    test wire::tests::prop_pubkey ... ok
    test wire::tests::prop_refs ... ok
    test wire::tests::prop_signature ... ok
    test wire::tests::prop_signed_refs ... ok
    test wire::tests::prop_string ... ok
    test wire::tests::prop_tuple ... ok
    test wire::tests::prop_u16 ... ok
    test wire::tests::prop_u32 ... ok
    test wire::tests::prop_u64 ... ok
    test wire::tests::prop_u8 ... ok
    test wire::tests::prop_vec ... ok
    test wire::tests::test_alias ... ok
    test wire::tests::test_bounded_vec_limit ... ok
    test wire::tests::test_filter_invalid ... ok
    test wire::tests::test_string ... ok
    test wire::varint::test::prop_encode_decode ... ok
    test wire::varint::test::test_encode_overflow - should panic ... ok
    test wire::varint::test::test_encoding ... ok
    test wire::message::tests::prop_message_decoder ... ok
    
    failures:
    
    ---- tests::e2e::test_connection_crossing stdout ----
    1758366581 test: Node::init alice: z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p
    1758366581 test: Node::init bob: z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU
    1758366581 tests::e2e::test_connection_crossing      node: Opening policy database..
    1758366581 tests::e2e::test_connection_crossing      node: Migrating COBs cache..
    1758366582 tests::e2e::test_connection_crossing      node: Migration of COBs cache complete (version=2)..
    1758366582 tests::e2e::test_connection_crossing      node: Default seeding policy set to 'block (all)'
    1758366582 tests::e2e::test_connection_crossing      node: Initializing service (Test)..
    1758366582 tests::e2e::test_connection_crossing      node: Opening node database..
    1758366582 tests::e2e::test_connection_crossing      node: Address book is empty. Adding bootstrap nodes..
    1758366582 tests::e2e::test_connection_crossing      node: 0 nodes added to address book
    1758366582 tests::e2e::test_connection_crossing   service: Init @1758366581924
    1758366582 tests::e2e::test_connection_crossing   service: Empty refs database, populating from storage..
    1758366582 tests::e2e::test_connection_crossing   service: Not enough available peers to connect to (available=0, wanted=8)
    1758366582 tests::e2e::test_connection_crossing      node: Binding control socket /tmp/.tmpSbw5ow/btAWWb0A/node/control.sock..
    1758366582 tests::e2e::test_connection_crossing      node: Opening policy database..
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <runtime>           node: Running node z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p in /tmp/.tmpSbw5ow/btAWWb0A..
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <signals>           node: Signal notifications channel error: receiving on an empty and disconnected channel
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           node: Listening on 0.0.0.0:42959..
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <control>        control: Control thread listening on socket..
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command QueryState(..)
    1758366582 tests::e2e::test_connection_crossing      node: Migrating COBs cache..
    1758366582 tests::e2e::test_connection_crossing      node: Migration of COBs cache complete (version=2)..
    1758366582 tests::e2e::test_connection_crossing      node: Default seeding policy set to 'block (all)'
    1758366582 tests::e2e::test_connection_crossing      node: Initializing service (Test)..
    1758366582 tests::e2e::test_connection_crossing      node: Opening node database..
    1758366582 tests::e2e::test_connection_crossing      node: Address book is empty. Adding bootstrap nodes..
    1758366582 tests::e2e::test_connection_crossing      node: 0 nodes added to address book
    1758366582 tests::e2e::test_connection_crossing   service: Init @1758366582070
    1758366582 tests::e2e::test_connection_crossing   service: Empty refs database, populating from storage..
    1758366582 tests::e2e::test_connection_crossing   service: Not enough available peers to connect to (available=0, wanted=8)
    1758366582 tests::e2e::test_connection_crossing      node: Binding control socket /tmp/.tmpSbw5ow/2VZcJg8H/node/control.sock..
    1758366582 test: Preferred peer is z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command QueryState(..)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command Connect(z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU, 0.0.0.0:46567, ConnectOptions { persistent: false, timeout: 30s })
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Connecting to z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (0.0.0.0:46567)..
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Attempted connection to z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (0.0.0.0:46567)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Registering outbound transport for z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (fd=27)..
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Outbound peer resource registered for z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU with id=2 (fd=27)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           node: Listening on 0.0.0.0:46567..
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           wire: Inbound connection from 127.0.0.1:45906 (fd=95)..
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           wire: Accepted inbound connection from 127.0.0.1:45906 (fd=95)..
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           wire: Inbound peer resource registered with id=2 (fd=95)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <runtime>           node: Running node z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU in /tmp/.tmpSbw5ow/2VZcJg8H..
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <signals>           node: Signal notifications channel error: receiving on an empty and disconnected channel
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <control>        control: Control thread listening on socket..
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Session established with z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (id=2) (fd=27) (outbound)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Connected to z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (0.0.0.0:46567) (Outbound)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Subscribing to messages since timestamp 1758280182214..
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>           wire: Session established with z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p (id=2) (fd=95) (inbound)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Connected to z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p (127.0.0.1:45906) (Inbound)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Subscribing to messages since timestamp 1758280182216..
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received command QueryState(..)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received command QueryState(..)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received node announcement of z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU with 0 address(es) from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (t=1758366582070)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received node announcement of z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p with 0 address(es) from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p (t=1758366581924)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Stored announcement from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU to be broadcast in 0 millisecond(s) (t=1758366582070)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Stored announcement from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p to be broadcast in 0 millisecond(s) (t=1758366581924)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Address store entry for node z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU updated at 1758366582070
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received inventory announcement of z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU with 0 item(s) from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (t=1758366582071)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Address store entry for node z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p updated at 1758366581924
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received inventory announcement of z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p with 0 item(s) from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p (t=1758366581925)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Stored announcement from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU to be broadcast in 0 millisecond(s) (t=1758366582071)
    1758366582 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received subscription filter from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Stored announcement from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p to be broadcast in 0 millisecond(s) (t=1758366581925)
    1758366582 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received subscription filter from z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command QueryState(..)
    1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received command QueryState(..)
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Received command QueryState(..)
    1758366583 test: Ok([Session { nid: PublicKey(z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU), link: Outbound, addr: Address(NetAddr { host: Ip(0.0.0.0), port: 46567 }), state: Connected { since: LocalTime { millis: 1758366582214 }, ping: None, fetching: {}, latencies: [], stable: false } }])
    1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <service>        service: Received command QueryState(..)
    1758366583 test: Ok([Session { nid: PublicKey(z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p), link: Inbound, addr: Address(NetAddr { host: Ip(127.0.0.1), port: 45906 }), state: Connected { since: LocalTime { millis: 1758366582216 }, ping: None, fetching: {}, latencies: [], stable: false } }])
    
    thread 'tests::e2e::test_connection_crossing' panicked at crates/radicle-node/src/tests/e2e.rs:859:9:
    assertion `left == right` failed
      left: Outbound
     right: Inbound
    note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
    1758366583 test: Node z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU shutting down..
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Disconnecting peer with id=2: connection reset
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>           wire: Transport handover for disconnecting peer with id=2 (fd=27)
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Disconnected from z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU (connection reset)
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Dropping peer z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU..
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <service>        service: Not enough available peers to connect to (available=0, wanted=8)
    1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <control>        control: Received `{"command":"shutdown"}` on control socket
    1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <control>        control: Shutdown requested..
    1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <runtime>           pool: Worker pool shutting down..
    1758366583 z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU <runtime>           node: Node shutdown completed for z6Mkv9dcuSiKGGTA3NERp9uijbt5tTjNk3JNiRwqBkAnaKeU
    1758366583 test: Node z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p shutting down..
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <control>        control: Received `{"command":"shutdown"}` on control socket
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <control>        control: Shutdown requested..
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <runtime>           pool: Worker pool shutting down..
    1758366583 z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p <runtime>           node: Node shutdown completed for z6Mktesey4t91Z49GjEr89cafqhhSZiUtpu5Em22fojCQi5p
    
    
    failures:
        tests::e2e::test_connection_crossing
    
    test result: FAILED. 112 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 104.91s
    
    error: test failed, to rerun pass `-p radicle-node --lib`
    ERROR: Action failed: command failed: ["cargo", "test", "--offline", "--locked", "--workspace"]
    ERROR: command failed: ["cargo", "test", "--offline", "--locked", "--workspace"]
    EXIT CODE: 1
    
    ====================
[2025-09-20T11:11:23Z DEBUG ambient_ci::qemu] QEMU exit code 1
ERROR: failed to run actions in QEMU

Ambient stderr

<empty log>

CI run for rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5

Table of Contents

Run log

Trigger message

Ambient stdout

Ambient stderr