Radicle repository id rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5
[2025-09-16T09:53:43Z INFO ambient] ambient starts
[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
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 26.1M 1 disk
vdd 254:48 0 9.3G 0 disk
vde 254:64 0 46.6G 0 disk
vdf 254:80 0 796.6M 1 disk
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad 0 2025-09-16 09:48 ./
-rw-r--r-- _rad/_rad 2454 2025-09-16 09:48 ./plan.yaml
-rwxr-xr-x _rad/_rad 3007128 2025-09-16 09:48 ./run-ci
INFO: Extracted files:
4587526 4 drwxr-xr-x 2 1001 1001 4096 Sep 16 09:48 .
4587527 4 -rw-r--r-- 1 1001 1001 2454 Sep 16 09:48 ./plan.yaml
4587530 2940 -rwxr-xr-x 1 1001 1001 3007128 Sep 16 09:48 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-09-16T09:48:52Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-09-16T09:48:52Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-09-16T09:48:52Z INFO ambient_execute_plan] ambient-execute-plan starts
[2025-09-16T09:48:52Z WARN ambient_execute_plan] ambient-execute-plan starts
[2025-09-16T09:48:52Z 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"
export RUSTDOCFLAGS='-D warnings'
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-16T09:48:52Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
pathname: "/workspace",
}
RUN: Action finished OK
RUN: Action Mkdir {
pathname: "/workspace/artifacts",
}
[2025-09-16T09:48:52Z 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-16T09:48:52Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
archive: "/dev/vdc",
directory: "/workspace/src",
}
[2025-09-16T09:48:52Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-09-16T09:48:53Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
archive: "/dev/vdf",
directory: "/workspace/deps",
}
[2025-09-16T09:48:53Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
archive: "/dev/vdf",
directory: "/workspace/deps",
}
[2025-09-16T09:48:53Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-09-16T09:49:11Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
archive: "/dev/vde",
directory: "/workspace/cache",
}
[2025-09-16T09:49:11Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
archive: "/dev/vde",
directory: "/workspace/cache",
}
[2025-09-16T09:49:11Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-09-16T09:50:34Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
argv: [
"find",
"/workspace",
"-maxdepth",
"2",
"-ls",
],
}
[2025-09-16T09:50:34Z 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 16 09:49 /workspace
2622199 4 drwxr-xr-x 3 root root 4096 Sep 16 09:48 /workspace/deps
2622200 0 -rw-r--r-- 1 root root 0 Sep 15 14:35 /workspace/deps/.package-cache-mutate
2622203 4 drwxr-xr-x 5 root root 4096 Sep 15 14:35 /workspace/deps/registry
2622201 120 -rw-r--r-- 1 root root 122880 Sep 16 09:48 /workspace/deps/.global-cache
2622202 0 -rw-r--r-- 1 root root 0 Sep 15 14:35 /workspace/deps/.package-cache
2621443 4 drwxr-xr-x 12 root root 4096 Sep 16 09:48 /workspace/src
2621499 4 -rw-r--r-- 1 root root 2653 Sep 16 09:48 /workspace/src/Cargo.toml
2622195 12 -rw-r--r-- 1 root root 9636 Sep 16 09:48 /workspace/src/CONTRIBUTING.md
2621500 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/.config
2621496 4 -rw-r--r-- 1 root root 1623 Sep 16 09:48 /workspace/src/build.rs
2621502 4 -rw-r--r-- 1 root root 426 Sep 16 09:48 /workspace/src/radicle-node.1.adoc
2621504 4 drwxr-xr-x 8 root root 4096 Sep 16 09:48 /workspace/src/.git
2622192 8 -rw-r--r-- 1 root root 7144 Sep 16 09:48 /workspace/src/rad-id.1.adoc
2621460 4 -rw-r--r-- 1 root root 2163 Sep 16 09:48 /workspace/src/VERSIONING.md
2621495 4 -rw-r--r-- 1 root root 3133 Sep 16 09:48 /workspace/src/README.md
2621615 12 -rw-r--r-- 1 root root 10770 Sep 16 09:48 /workspace/src/rad-patch.1.adoc
2622196 4 -rw-r--r-- 1 root root 79 Sep 16 09:48 /workspace/src/ARCHITECTURE.md
2621623 4 drwxr-xr-x 17 root root 4096 Sep 16 09:48 /workspace/src/crates
2621448 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/scripts
2621444 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/.radicle
2621467 8 -rw-r--r-- 1 root root 8167 Sep 16 09:48 /workspace/src/CHANGELOG.md
2621482 4 drwxr-xr-x 3 root root 4096 Sep 16 09:48 /workspace/src/debian
2621465 4 -rw-r--r-- 1 root root 42 Sep 16 09:48 /workspace/src/.envrc
2622193 4 -rw-r--r-- 1 root root 1203 Sep 16 09:48 /workspace/src/DCO
2622188 4 drwxr-xr-x 3 root root 4096 Sep 16 09:48 /workspace/src/.github
2622198 116 -rw-r--r-- 1 root root 117616 Sep 16 09:48 /workspace/src/Cargo.lock
2621616 4 drwxr-xr-x 5 root root 4096 Sep 16 09:48 /workspace/src/systemd
2621614 12 -rw-r--r-- 1 root root 9723 Sep 16 09:48 /workspace/src/LICENSE-APACHE
2621498 4 -rw-r--r-- 1 root root 101 Sep 16 09:48 /workspace/src/.gitignore
2622194 8 -rw-r--r-- 1 root root 5102 Sep 16 09:48 /workspace/src/flake.lock
2621464 4 -rw-r--r-- 1 root root 77 Sep 16 09:48 /workspace/src/rust-toolchain.toml
2621497 12 -rw-r--r-- 1 root root 10500 Sep 16 09:48 /workspace/src/flake.nix
2621468 4 -rw-r--r-- 1 root root 571 Sep 16 09:48 /workspace/src/git-remote-rad.1.adoc
2621466 4 -rw-r--r-- 1 root root 61 Sep 16 09:48 /workspace/src/.env.seed
2621503 8 -rw-r--r-- 1 root root 5432 Sep 16 09:48 /workspace/src/deny.toml
2621458 8 -rw-r--r-- 1 root root 7645 Sep 16 09:48 /workspace/src/HACKING.md
2621447 4 -rw-r--r-- 1 root root 1079 Sep 16 09:48 /workspace/src/LICENSE-MIT
2621463 8 -rw-r--r-- 1 root root 8023 Sep 16 09:48 /workspace/src/rad.1.adoc
2621469 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/build
2622197 4 -rw-r--r-- 1 root root 1083 Sep 16 09:48 /workspace/src/.gitsigners
2621459 4 -rw-r--r-- 1 root root 30 Sep 16 09:48 /workspace/src/.dockerignore
2621461 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/.cargo
2621442 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/artifacts
2648928 4 drwxr-xr-x 6 root root 4096 Sep 16 08:29 /workspace/cache
2665369 4 -rw-r--r-- 1 root root 218 Sep 16 08:29 /workspace/cache/.rustdoc_fingerprint.json
2753975 4 drwxr-xr-x 23 root root 4096 Sep 16 08:29 /workspace/cache/doc
2648930 4 drwxr-xr-x 7 root root 4096 Sep 16 08:26 /workspace/cache/debug
2648929 4 drwxr-xr-x 2 root root 4096 Sep 16 08:23 /workspace/cache/tmp
2668750 4 -rw-r--r-- 1 root root 1032 Sep 16 08:25 /workspace/cache/.rustc_info.json
2665370 4 drwxr-xr-x 7 root root 4096 Sep 16 08:33 /workspace/cache/release
RUN: Action finished OK
RUN: Action CargoFmt
[2025-09-16T09:50:34Z 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-16T09:50:36Z 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.10.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)
Compiling radicle-cli v0.16.0 (/workspace/src/crates/radicle-cli)
Compiling radicle-node v0.15.0 (/workspace/src/crates/radicle-node)
Checking radicle-term v0.15.0 (/workspace/src/crates/radicle-term)
Checking radicle-crypto v0.13.0 (/workspace/src/crates/radicle-crypto)
Checking radicle-systemd v0.10.0 (/workspace/src/crates/radicle-systemd)
Compiling radicle-remote-helper v0.13.0 (/workspace/src/crates/radicle-remote-helper)
Checking radicle-cob v0.16.0 (/workspace/src/crates/radicle-cob)
Checking radicle v0.19.0 (/workspace/src/crates/radicle)
Checking radicle-fetch v0.15.0 (/workspace/src/crates/radicle-fetch)
Checking radicle-cli-test v0.13.0 (/workspace/src/crates/radicle-cli-test)
Checking radicle-schemars v0.5.0 (/workspace/src/crates/radicle-schemars)
Checking radicle-protocol v0.3.0 (/workspace/src/crates/radicle-protocol)
Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.52s
RUN: Action finished OK
RUN: Action CargoBuild
[2025-09-16T09:50:52Z 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.10.0 (/workspace/src/crates/radicle-ssh)
Compiling radicle-dag v0.10.0 (/workspace/src/crates/radicle-dag)
Compiling radicle-signals v0.11.0 (/workspace/src/crates/radicle-signals)
Compiling radicle-node v0.15.0 (/workspace/src/crates/radicle-node)
Compiling radicle-crypto v0.13.0 (/workspace/src/crates/radicle-crypto)
Compiling radicle-cli v0.16.0 (/workspace/src/crates/radicle-cli)
Compiling radicle-term v0.15.0 (/workspace/src/crates/radicle-term)
Compiling radicle-systemd v0.10.0 (/workspace/src/crates/radicle-systemd)
Compiling radicle-cob v0.16.0 (/workspace/src/crates/radicle-cob)
Compiling radicle-remote-helper v0.13.0 (/workspace/src/crates/radicle-remote-helper)
Compiling radicle v0.19.0 (/workspace/src/crates/radicle)
Compiling radicle-fetch v0.15.0 (/workspace/src/crates/radicle-fetch)
Compiling radicle-protocol v0.3.0 (/workspace/src/crates/radicle-protocol)
Compiling radicle-cli-test v0.13.0 (/workspace/src/crates/radicle-cli-test)
Compiling radicle-schemars v0.5.0 (/workspace/src/crates/radicle-schemars)
Finished `dev` profile [unoptimized + debuginfo] target(s) in 39.52s
RUN: Action finished OK
RUN: Action CargoTest
[2025-09-16T09:51:31Z 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 2.17s
Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle-51334f29bf16c6df)
running 237 tests
test cob::cache::migrations::_2::tests::test_migration_2 ... ok
test canonical::formatter::test::ascii_control_characters ... ok
test canonical::formatter::test::ordered_nested_object ... ok
test canonical::formatter::test::securesystemslib_asserts ... ok
test cob::cache::migrations::_2::tests::test_patch_json_deserialization ... ok
test cob::common::test::test_color ... ok
test cob::cache::tests::test_check_version ... ok
test cob::common::test::test_emojis ... ok
test cob::cache::tests::test_migrate_to ... 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_remove_delegate_concurrent ... ok
test cob::identity::test::test_identity_reject_concurrent ... ok
test cob::identity::test::test_identity_updates ... ok
test cob::identity::test::test_identity_update_rejected ... 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_identity_updates_concurrent ... 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::identity::test::test_identity_updates_concurrent_outdated ... ok
test cob::issue::test::test_invalid_actions ... ok
test cob::issue::test::test_invalid_tx ... ok
test cob::issue::test::test_concurrency ... ok
test cob::issue::test::test_invalid_tx_reference ... ok
test cob::issue::test::test_invalid_cob ... ok
test cob::issue::test::test_issue_all ... ok
test cob::issue::test::test_issue_create_and_assign ... 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_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_multilines ... ok
test cob::issue::test::test_issue_label ... ok
test cob::issue::test::test_issue_state_serde ... ok
test cob::issue::test::test_ordering ... ok
test cob::patch::actions::test::test_review_edit ... ok
test cob::issue::test::test_issue_edit_description ... ok
test cob::issue::test::test_issue_react ... ok
test cob::patch::cache::tests::test_get ... ok
test cob::patch::cache::tests::test_is_empty ... ok
test cob::issue::test::test_issue_reply ... ok
test cob::patch::cache::tests::test_find_by_revision ... ok
test cob::patch::cache::tests::test_list ... ok
test cob::patch::encoding::review::test::test_review_deserialize_summary_migration_null_summary ... ok
test cob::patch::encoding::review::test::test_review_deserialize_summary_migration_with_summary ... ok
test cob::patch::encoding::review::test::test_review_deserialize_summary_migration_without_summary ... ok
test cob::patch::encoding::review::test::test_review_deserialize_summary_v2 ... ok
test cob::patch::encoding::review::test::test_review_summary ... ok
test cob::patch::test::test_json ... ok
test cob::patch::test::test_json_serialization ... ok
test cob::patch::cache::tests::test_list_by_status ... 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::cache::tests::test_counts ... ok
test cob::patch::test::test_patch_redact ... ok
test cob::patch::cache::tests::test_remove ... ok
test cob::patch::test::test_patch_review_comment ... ok
test cob::patch::test::test_patch_review ... 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_remove_summary ... 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_review_edit_comment ... ok
test cob::stream::tests::test_all_from ... ok
test cob::patch::test::test_patch_review_revision_redact ... ok
test cob::stream::tests::test_all_from_until ... ok
test cob::stream::tests::test_all_until ... ok
test cob::stream::tests::test_regression_from_until ... ok
test cob::thread::tests::test_comment_edit_missing ... ok
test cob::thread::tests::test_comment_edit_redacted ... ok
test cob::stream::tests::test_from_until ... ok
test cob::thread::tests::test_comment_redact_missing ... ok
test cob::thread::tests::test_edit_comment ... ok
test cob::thread::tests::test_duplicate_comments ... ok
test cob::thread::tests::test_redact_comment ... ok
test git::canonical::quorum::test::merge_base_commutative ... ok
test git::canonical::quorum::test::test_merge_bases ... ok
test cob::thread::tests::test_timeline ... ok
test git::canonical::rules::tests::test_deserialization ... ok
test git::canonical::rules::tests::test_deserialize_extensions ... ok
test git::canonical::rules::tests::test_order ... ok
test git::canonical::rules::tests::test_roundtrip ... ok
test git::canonical::rules::tests::test_canonical ... ok
test git::canonical::rules::tests::test_rule_validate_success ... ok
test git::canonical::rules::tests::test_special_branches ... ok
test git::canonical::tests::test_commit_quorum_fork_of_a_fork ... ok
test git::canonical::tests::test_commit_quorum_forked_merge_commits ... ok
test git::canonical::tests::test_commit_quorum_groups ... ok
test git::canonical::tests::test_commit_quorum_linear ... ok
test git::canonical::tests::test_commit_quorum_merges ... ok
test git::canonical::tests::test_commit_quorum_single ... ok
test git::canonical::tests::test_commit_quorum_three_way_fork ... ok
test git::canonical::tests::test_commit_quorum_two_way_fork ... ok
test git::canonical::tests::test_quorum_different_types ... ok
test git::canonical::rules::tests::test_rule_validate_failures ... ok
test git::canonical::tests::test_tag_quorum ... 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::patch::test::test_patch_update ... 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::doc::update::test::test_can_update_crefs ... ok
test identity::doc::update::test::test_cannot_include_default_branch_rule ... ok
test identity::doc::update::test::test_default_branch_rule_exists_after_verification ... 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 git::canonical::tests::test_quorum_properties ... 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_remove_nothing ... ok
test node::address::store::test::test_node_aliases ... ok
test node::config::test::partial ... ok
test node::features::test::test_operations ... ok
test node::db::test::test_version ... 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_policies ... ok
test node::policy::store::test::test_node_aliases ... 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_duplicate ... ok
test node::routing::test::test_insert_existing_updated_time ... ok
test node::routing::test::test_insert_and_remove ... ok
test node::routing::test::test_len ... 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::all_synced_nodes_are_preferred_seeds ... ok
test node::routing::test::test_prune ... ok
test node::sync::announce::test::announcer_adapts_target_to_reach ... ok
test node::sync::announce::test::announcer_preferred_seeds_or_replica_factor ... 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_reached_preferred_seeds ... ok
test cob::thread::tests::prop_ordering ... ok
test node::sync::announce::test::announcer_timed_out ... ok
test node::sync::announce::test::announcer_with_replication_factor_zero_and_preferred_seeds ... ok
test node::sync::announce::test::announcer_synced_with_unknown_node ... ok
test node::sync::announce::test::construct_node_appears_in_multiple_input_sets ... ok
test node::sync::announce::test::construct_only_preferred_seeds_provided ... ok
test node::sync::announce::test::invariant_progress_should_match_state ... ok
test node::sync::announce::test::cannot_construct_announcer ... ok
test node::sync::announce::test::local_node_in_preferred_seeds ... ok
test node::sync::announce::test::local_node_in_synced_set ... ok
test node::sync::announce::test::local_node_only_in_all_sets_results_in_no_seeds_error ... ok
test node::sync::announce::test::local_node_in_unsynced_set ... ok
test node::sync::announce::test::synced_with_local_node_is_ignored ... ok
test node::sync::announce::test::preferred_seeds_already_synced ... ok
test node::sync::announce::test::timed_out_after_reaching_success ... ok
test node::sync::announce::test::synced_with_same_node_multiple_times ... ok
test node::sync::announce::test::local_node_in_multiple_sets ... 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::ignores_duplicates_and_local_node ... ok
test node::sync::fetch::test::could_not_reach_target ... 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::test::ensure_replicas_construction ... ok
test node::sync::fetch::test::reaches_target_of_preferred_seeds ... ok
test node::sync::test::replicas_constrain_to ... ok
test node::sync::fetch::test::reaches_target_of_replicas ... 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::test::canonicalize_home ... ok
test profile::test::test_config ... ok
test rad::tests::test_fork ... ok
test rad::tests::test_checkout ... ok
test serde_ext::test::test_localtime ... ok
test serde_ext::test::test_localtime_ext ... ok
test rad::tests::test_init ... ok
test storage::git::tests::test_references_of ... 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::git::tests::test_remote_refs ... 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 profile::config::test::schema ... ok
test storage::refs::tests::test_rid_verification ... ok
test identity::doc::test::prop_encode_decode ... ok
test result: ok. 237 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.15s
Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cli-621b4b410a8c352b)
running 14 tests
test commands::patch::review::builder::tests::test_review_comments_basic ... ok
test commands::inspect::test::test_tree ... ok
test commands::patch::review::builder::tests::test_review_comments_split_hunk ... ok
test git::ddiff::tests::diff_encode_decode_ddiff_hunk ... ok
test commands::patch::review::builder::tests::test_review_comments_multiline ... 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 terminal::patch::test::test_edit_display_message ... ok
test terminal::patch::test::test_update_display_message ... ok
test terminal::patch::test::test_create_display_message ... ok
test commands::patch::review::builder::tests::test_review_comments_before ... ok
test result: ok. 13 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.03s
Running unittests src/main.rs (/workspace/cache/debug/deps/rad-90c5a176eb08002b)
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-c121b642be9ff500)
running 104 tests
test framework_home ... ok
test git_push_and_fetch ... ok
test git_push_amend ... ok
test git_push_canonical_annotated_tags ... ok
test git_push_canonical_lightweight_tags ... ok
test git_push_diverge ... ok
test rad_auth ... ok
test rad_auth_errors ... ok
test git_tag ... FAILED
test git_push_rollback ... ok
test rad_block ... ok
test rad_checkout ... ok
test git_push_converge ... ok
test rad_clone ... ok
test rad_clone_bare ... ok
test rad_clean ... ok
test rad_clone_all ... ok
test rad_clone_unknown ... ok
test rad_clone_directory ... ok
test rad_clone_connect ... ok
test rad_cob_log ... ok
test rad_cob_migrate ... ok
test rad_cob_multiset ... ok
test rad_clone_partial_fail ... ok
test rad_cob_operations ... ok
test rad_cob_update_identity ... ok
test rad_cob_show ... ok
test rad_cob_update ... ok
test rad_diff ... ok
test rad_config ... ok
test rad_id_collaboration ... ignored, slow
test rad_id ... ok
test rad_id_conflict ... ok
test rad_id_private ... ok
test rad_fork ... ok
test rad_fetch ... ok
test rad_id_multi_delegate ... ok
test rad_id_unknown_field ... ok
test rad_id_update_delete_field ... ok
test rad_init ... ignored, part of many other tests
test rad_id_threshold ... ok
test rad_id_threshold_soft_fork ... ok
test rad_init_bare ... ok
test rad_init_detached_head ... ok
test rad_init_existing ... ok
test rad_init_existing_bare ... ok
test rad_init_no_git ... ok
test rad_init_no_seed ... ok
test rad_init_private ... ok
test rad_init_private_no_seed ... ok
test rad_inbox ... ok
test rad_init_private_clone ... ok
test rad_init_private_clone_seed ... ok
test rad_init_sync_not_connected ... ok
test rad_init_private_seed ... ok
test rad_init_with_existing_remote ... ok
test rad_init_sync_preferred ... ok
test rad_inspect ... ok
test rad_issue ... ok
test rad_merge_after_update ... ok
test rad_merge_no_ff ... ok
test rad_merge_via_push ... ok
test rad_init_sync_and_clone ... ok
test rad_node_connect ... ok
test rad_init_sync_timeout ... ok
test rad_node ... ok
test rad_node_connect_without_address ... ok
test rad_patch_change_base ... ok
test rad_patch_ahead_behind ... ok
test rad_patch ... ok
test rad_patch_checkout ... ok
test rad_patch_checkout_revision ... ok
test rad_patch_detached_head ... ok
test rad_patch_diff ... ok
test rad_patch_draft ... ok
test rad_patch_checkout_force ... ok
test rad_patch_edit ... ok
test rad_patch_fetch_2 ... FAILED
test rad_patch_merge_draft ... ok
test rad_patch_fetch_1 ... ok
test rad_patch_revert_merge ... ok
test rad_patch_update ... ok
test rad_patch_open_explore ... ok
test rad_patch_delete ... ok
test rad_patch_via_push ... FAILED
test rad_publish ... ok
test rad_review_by_hunk ... ok
test rad_seed_and_follow ... ok
test rad_patch_pull_update ... ok
test rad_self ... ok
test rad_push_and_pull_patches ... ok
test rad_remote ... ok
test rad_seed_many ... ok
test rad_unseed ... ok
test rad_unseed_many ... ok
test rad_sync_without_node ... ok
test rad_warn_old_nodes ... ok
test test_clone_without_seeds ... ok
test rad_watch ... ok
test rad_sync ... ok
test test_cob_deletion ... ok
test test_cob_replication ... ok
test rad_workflow ... ok
test test_replication_via_seed ... ok
failures:
---- git_tag stdout ----
1758016331 [36mtest:[0m [36mUsing PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-nXtOYX/work/alice"][0m
1758016331 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["init", "--name", "heartwood", "--description", "Radicle Heartwood Protocol & Stack", "--no-confirm", "--public", "-v"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
1758016331 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
1758016331 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
1758016331 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
[2m1758016331 git_tag node: Opening policy database..[0m
[2m1758016331 git_tag node: Default seeding policy set to 'block (all)'[0m
[2m1758016331 git_tag node: Initializing service (Test)..[0m
[2m1758016331 git_tag node: Opening node database..[0m
[2m1758016331 git_tag node: Address book is empty. Adding bootstrap nodes..[0m
[2m1758016331 git_tag node: 0 nodes added to address book[0m
[2m1758016331 git_tag service: Init @1758016331522[0m
[2m1758016331 git_tag service: Empty refs database, populating from storage..[0m
[2m1758016331 git_tag service: Saved local sync status for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji..[0m
[2m1758016331 git_tag service: Adding refs announcement for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to historical gossip messages..[0m
[2m1758016332 git_tag service: Not enough available peers to connect to (available=0, wanted=8)[0m
[2m1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> node: Listening on 0.0.0.0:36645..[0m
[2m1758016332 git_tag node: Binding control socket /tmp/radicle-nXtOYX/home/alice/.radicle/node/control.sock..[0m
[2m1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> node: Running node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi in /tmp/radicle-nXtOYX/home/alice/.radicle..[0m
[2m1758016332 git_tag node: Opening policy database..[0m
[2m1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Control thread listening on socket..[0m
[33m1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <signals> node: Signal notifications channel error: receiving on an empty and disconnected channel[0m
[2m1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received command QueryState(..)[0m
[2m1758016332 git_tag node: Default seeding policy set to 'block (all)'[0m
[2m1758016332 git_tag node: Initializing service (Test)..[0m
[2m1758016332 git_tag node: Opening node database..[0m
[2m1758016333 git_tag node: Address book is empty. Adding bootstrap nodes..[0m
[2m1758016333 git_tag node: 0 nodes added to address book[0m
[2m1758016333 git_tag service: Init @1758016332016[0m
[2m1758016333 git_tag service: Empty refs database, populating from storage..[0m
[2m1758016333 git_tag service: Not enough available peers to connect to (available=0, wanted=8)[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> node: Listening on 0.0.0.0:44055..[0m
[2m1758016333 git_tag node: Binding control socket /tmp/radicle-nXtOYX/home/bob/.radicle/node/control.sock..[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> node: Running node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk in /tmp/radicle-nXtOYX/home/bob/.radicle..[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Control thread listening on socket..[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)[0m
[33m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <signals> node: Signal notifications channel error: receiving on an empty and disconnected channel[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Connect(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi, 0.0.0.0:36645, ConnectOptions { persistent: false, timeout: 30s })[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Connecting to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:36645)..[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Attempted connection to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:36645)[0m
[2m1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Inbound connection from 127.0.0.1:48342 (fd=203)..[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Registering outbound transport for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (fd=202)..[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Outbound peer resource registered for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with id=2 (fd=202)[0m
[2m1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Accepted inbound connection from 127.0.0.1:48342 (fd=203)..[0m
[2m1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Inbound peer resource registered with id=2 (fd=203)[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Session established with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (id=2) (fd=202) (outbound)[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Connected to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:36645) (Outbound)[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Subscribing to messages since timestamp 1757929933615..[0m
[2m1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Session established with z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (id=2) (fd=203) (inbound)[0m
[2m1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Connected to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (127.0.0.1:48342) (Inbound)[0m
[2m1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Subscribing to messages since timestamp 1757929933615..[0m
1758016333 [36mtest:[0m [36mNode z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi has converged[0m
1758016333 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
[2m1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received node announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 address(es) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1758016332016)[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received node announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 0 address(es) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1758016331522)[0m
1758016333 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
[2m1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1758016332016)[0m
[2m1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1758016331522)[0m
1758016333 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
1758016333 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
[2m1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Address store entry for node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi updated at 1758016331522[0m
[2m1758016334 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Address store entry for node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk updated at 1758016332016[0m
[2m1758016334 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1758016332017)[0m
[2m1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1758016331524)[0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
[2m1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1758016331524)[0m
[2m1758016334 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1758016332017)[0m
[2m1758016334 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
1758016334 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
[2m1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received subscription filter from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
1758016334 [36mtest:[0m [36mNode z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk has converged[0m
1758016335 [36mtest:[0m [36mUsing PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-nXtOYX/work/bob"][0m
1758016335 [36mtest:[0m [36mrad-clone.md: Running `/workspace/cache/debug/rad` with ["clone", "rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji", "--scope", "followed"] in `/tmp/radicle-nXtOYX/work/bob`..[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"status"}` on control socket[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"seed","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","scope":"followed"}` on control socket[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Seed(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, followed)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending subscription filter to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"seeds","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"}` on control socket[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Found 1 connected seed(s) and 0 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"session","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"}` on control socket[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"fetch","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi","timeout":{"secs":9,"nanos":0}}` on control socket[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Fetch(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [outbound connected] (all remotes)..[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Opened new stream with id 12 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received `open` command for stream 12 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Worker processing incoming fetch for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk on stream 12..[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> worker: z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk cloning from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 12..[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched rad/id (18ms)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched data for 1 remote(s) (37ms)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched data refs for 1 remotes (50ms)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received `end-of-file` on stream 12 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <upload-pack> worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Upload pack finished (62ms)[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Upload process on stream 12 exited with result Ok(())[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received fetch result from worker for stream 12, remote z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk: Responder { rid: Some(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)), result: Ok(()) }[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Stream 12 of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk closing with 0 byte(s) sent and 0 byte(s) received[0m
[2m1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Received `close` command for stream 12 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Stream 12 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Validated 1 remote(s) (52ms)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Applied updates (54ms)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Finished clone of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (67ms)[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: refs/rad/id -> 0656c217f917c3e06234771e9ecae53aba5e173e[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: refs/heads/master -> f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: HEAD -> refs/heads/master[0m
[2m1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Received fetch result from worker for stream 12, remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi: Initiator { rid: RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), result: Ok(FetchResult { updated: [Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id"), oid: Oid(0656c217f917c3e06234771e9ecae53aba5e173e) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs"), oid: Oid(99c549702e2bcfe02b0e68d4a2224fb7a1524529) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0656c217f917c3e06234771e9ecae53aba5e173e"), oid: Oid(0656c217f917c3e06234771e9ecae53aba5e173e) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master"), oid: Oid(f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354) }, Skipped { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id"), oid: Oid(0656c217f917c3e06234771e9ecae53aba5e173e) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root"), oid: Oid(0656c217f917c3e06234771e9ecae53aba5e173e) }], canonical: UpdatedCanonicalRefs { inner: {Qualified(RefString("refs/heads/master")): Oid(f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354)} }, namespaces: {PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi)}, clone: true, doc: DocAt { commit: Oid(0656c217f917c3e06234771e9ecae53aba5e173e), blob: Oid(d96f425412c9f8ad5d9a9a05c9831d0728e2338d), doc: Doc { version: Version(1), payload: {PayloadId(TypeName("xyz.radicle.project")): Payload { value: Object {"defaultBranch": String("master"), "description": String("Radicle Heartwood Protocol & Stack"), "name": String("heartwood")} }}, delegates: Delegates(NonEmpty { head: Did("did:key:z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"), tail: [] }), threshold: Threshold(1), visibility: Public } } }) }[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Found existing fetch request from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi, sending result..[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sent fetch result for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi..[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully[0m
1758016336 [36mtest:[0m [36mrad-clone.md: Running `cd /tmp/radicle-nXtOYX/work/bob/heartwood`..[0m
1758016336 [36mtest:[0m [36mrad-clone.md: Running `ls` with [] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..[0m
1758016336 [36mtest:[0m [36mrad-clone.md: Running `cat` with ["README"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..[0m
1758016336 [36mtest:[0m [36mrad-clone.md: Running `git` with ["status"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..[0m
1758016336 [36mtest:[0m [36mrad-clone.md: Running `git` with ["branch", "--remotes"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..[0m
1758016336 [36mtest:[0m [36mrad-clone.md: Running `git` with ["remote", "-v"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..[0m
1758016336 [36mtest:[0m [36mrad-clone.md: Running `git` with ["log", "-n", "1"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..[0m
1758016336 [36mtest:[0m [36mrad-clone.md: Running `/workspace/cache/debug/rad` with ["ls", "--seeded"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..[0m
1758016336 [36mtest:[0m [36mUsing PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-nXtOYX"][0m
1758016336 [36mtest:[0m [36mgit-tag.md: Running `touch` with ["LICENSE"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
1758016336 [36mtest:[0m [36mgit-tag.md: Running `git` with ["add", "LICENSE"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..99c5497 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0656c217f917c3e06234771e9ecae53aba5e173e for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..f2de534 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Updating and announcing inventory for cloned repository rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji..[0m
1758016336 [36mtest:[0m [36mgit-tag.md: Running `git` with ["commit", "-am", "Add LICENSE"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
1758016336 [36mtest:[0m [36mgit-tag.md: Running `git` with ["tag", "v1.0", "-a", "-m", "Release v1.0"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
1758016336 [36mtest:[0m [36mgit-tag.md: Running `git` with ["push", "rad", "v1.0"] in `/tmp/radicle-nXtOYX/work/alice`..[0m
[2m1758016336 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"status"}` on control socket[0m
[2m1758016336 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"seeds","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"}` on control socket[0m
[2m1758016336 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)[0m
[2m1758016336 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Found 0 connected seed(s) and 1 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
thread 'git_tag' panicked at crates/radicle-cli-test/src/lib.rs:489:36:
--- Expected
++++ actual: stderr
1 - ✓ Synced with 1 seed(s)
1 + No seeds found for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji.
2 2 | To rad://z42hL2jL4XNk6K8oHQaSWfMgCL7ji/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
3 3 | * [new tag] v1.0 -> v1.0
Exit status: 0
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
1758016336 [36mtest:[0m [36mNode z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk shutting down..[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"shutdown"}` on control socket[0m
[2m1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Shutdown requested..[0m
[2m1758016337 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 1 item(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1758016336239)[0m
[2m1758016337 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1758016336240)[0m
[2m1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Disconnecting peer with id=2: connection reset[0m
[2m1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Transport handover for disconnecting peer with id=2 (fd=203)[0m
[2m1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Disconnected from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (connection reset)[0m
[2m1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Dropping peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk..[0m
[2m1758016337 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> pool: Worker pool shutting down..[0m
[2m1758016337 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> node: Node shutdown completed for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
1758016337 [36mtest:[0m [36mNode z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi shutting down..[0m
[2m1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"shutdown"}` on control socket[0m
[2m1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Shutdown requested..[0m
[2m1758016338 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> pool: Worker pool shutting down..[0m
[2m1758016338 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> node: Node shutdown completed for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
---- rad_patch_fetch_2 stdout ----
1758016387 [36mtest:[0m [36mUsing PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-PjI6mV/work/alice"][0m
1758016387 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["init", "--name", "heartwood", "--description", "Radicle Heartwood Protocol & Stack", "--no-confirm", "--public", "-v"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mUsing PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-PjI6mV/work/alice"][0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["checkout", "-b", "alice/1", "-q"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["commit", "--allow-empty", "-m", "Changes #1", "-q"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["push", "rad", "-o", "patch.message=Changes", "HEAD:refs/patches"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["checkout", "master", "-q"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["branch", "-D", "alice/1", "-q"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["update-ref", "-d", "refs/remotes/rad/alice/1"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["update-ref", "-d", "refs/remotes/rad/patches/5e2dedcc5d515fcbc1cca483d3376609fe889bfb"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["gc", "--prune=now"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["branch", "-r"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["pull"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
1758016387 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["branch", "-r"] in `/tmp/radicle-PjI6mV/work/alice`..[0m
thread 'rad_patch_fetch_2' panicked at crates/radicle-cli-test/src/lib.rs:491:36:
--- Expected
++++ actual: stdout
1 - rad/HEAD -> rad/master
2 1 | rad/master
3 2 | rad/patches/5e2dedcc5d515fcbc1cca483d3376609fe889bfb
Exit status: 0
---- rad_patch_via_push stdout ----
1758016396 [36mtest:[0m [36mUsing PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-m1WeYF/work/alice"][0m
1758016396 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["init", "--name", "heartwood", "--description", "Radicle Heartwood Protocol & Stack", "--no-confirm", "--public", "-v"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mUsing PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-m1WeYF/work/alice"][0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["checkout", "-b", "feature/1"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["commit", "-a", "-m", "Add things", "-q", "--allow-empty"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["push", "-o", "patch.message=Add things #1", "-o", "patch.message=See commits for details.", "rad", "HEAD:refs/patches"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `/workspace/cache/debug/rad` with ["patch", "show", "6035d2f582afbe01ff23ea87528ae523d76875b6"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["branch", "-vv"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["status", "--short", "--branch"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["fetch"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["push"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
1758016396 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["show-ref"] in `/tmp/radicle-m1WeYF/work/alice`..[0m
thread 'rad_patch_via_push' panicked at crates/radicle-cli-test/src/lib.rs:491:36:
--- Expected
++++ actual: stdout
1 1 | 42d894a83c9c356552a57af09ccdbd5587a99045 refs/heads/feature/1
2 2 | f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 refs/heads/master
3 - f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 refs/remotes/rad/HEAD
4 3 | f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 refs/remotes/rad/master
5 4 | 42d894a83c9c356552a57af09ccdbd5587a99045 refs/remotes/rad/patches/6035d2f582afbe01ff23ea87528ae523d76875b6
Exit status: 0
failures:
git_tag
rad_patch_fetch_2
rad_patch_via_push
test result: FAILED. 99 passed; 3 failed; 2 ignored; 0 measured; 0 filtered out; finished in 121.03s
error: test failed, to rerun pass `-p radicle-cli --test commands`
ERROR: Action failed: command failed: ["cargo", "test", "--offline", "--locked", "--workspace"]
ERROR: command failed: ["cargo", "test", "--offline", "--locked", "--workspace"]
EXIT CODE: 1
[2025-09-16T09:53:43Z INFO ambient] ambient ends successfully
{
"request": "trigger",
"version": 1,
"event_type": "patch",
"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"
]
},
"action": "Created",
"patch": {
"id": "614558c6089af0679a3ebadad41b422f94c2019f",
"author": {
"id": "did:key:z6MkkPvBfjP4bQmco5Dm7UGsX2ruDBieEHi8n9DVJWX5sTEz",
"alias": "lorenz"
},
"title": "Use `git {send,fetch}-pack` instead of `git {push,fetch}` internally",
"state": {
"status": "open",
"conflicts": []
},
"before": "f9ff484c9e049fc86c75e27070290ceeaa5a3716",
"after": "cdd209e5da7031128950bde9440996c4a093fa48",
"commits": [
"cdd209e5da7031128950bde9440996c4a093fa48",
"3124547a2505848735717b7c18368c267e1d7d7e",
"20663a4e39d5ac28ad943df12de1156e76921991"
],
"target": "43246fe6c87bdc6c9110332c717521e5613677f3",
"labels": [],
"assignees": [],
"revisions": [
{
"id": "614558c6089af0679a3ebadad41b422f94c2019f",
"author": {
"id": "did:key:z6MkkPvBfjP4bQmco5Dm7UGsX2ruDBieEHi8n9DVJWX5sTEz",
"alias": "lorenz"
},
"description": "",
"base": "f9ff484c9e049fc86c75e27070290ceeaa5a3716",
"oid": "cdd209e5da7031128950bde9440996c4a093fa48",
"timestamp": 1758016013
}
]
}
}[2025-09-16T09:48:26Z INFO ambient] ambient starts
[2025-09-16T09:48:26Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-09-16T09:48:26Z DEBUG ambient] complete configuration: Config {
tmpdir: "/srv/tmp",
image_store: "/home/_rad/.local/state/ambient-ci/images",
projects: "/home/_rad/ambient-projects.yaml",
state: "/srv/ambient-state",
rsync_target: None,
rsync_target_base: None,
rsync_target_map: None,
dput_target: None,
executor: Some(
"/usr/bin/ambient-execute-plan",
),
artifacts_max_size: Byte(
10000000000,
),
cache_max_size: Byte(
50000000000,
),
qemu: QemuConfig {
cpus: 4,
memory: Byte(
8000000000,
),
kvm_binary: "/usr/bin/kvm",
ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
},
}
[2025-09-16T09:48:26Z DEBUG ambient] configuration: Config {
tmpdir: "/srv/tmp",
image_store: "/home/_rad/.local/state/ambient-ci/images",
projects: "/home/_rad/ambient-projects.yaml",
state: "/srv/ambient-state",
rsync_target: None,
rsync_target_base: None,
rsync_target_map: None,
dput_target: None,
executor: Some(
"/usr/bin/ambient-execute-plan",
),
artifacts_max_size: Byte(
10000000000,
),
cache_max_size: Byte(
50000000000,
),
qemu: QemuConfig {
cpus: 4,
memory: Byte(
8000000000,
),
kvm_binary: "/usr/bin/kvm",
ovmf_vars_file: "/usr/share/ovmf/OVMF.fd",
ovmf_code_file: "/usr/share/ovmf/OVMF.fd",
},
}
[2025-09-16T09:48:26Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/meta.yaml
[2025-09-16T09:48:26Z DEBUG ambient_ci::run] latest commit: "e35eb33f0e73a593a887f39eaa5f506da652e468"
[2025-09-16T09:48:26Z DEBUG ambient_ci::run] is a git repository
[2025-09-16T09:48:26Z DEBUG ambient_ci::run] git repository is clean
[2025-09-16T09:48:26Z DEBUG ambient_ci::run] current (HEAD) commit: cdd209e5da7031128950bde9440996c4a093fa48
[2025-09-16T09:48:26Z DEBUG ambient_ci::run] no dry run requested
[2025-09-16T09:48:26Z DEBUG ambient_ci::run] forced run requested
[2025-09-16T09:48:26Z DEBUG ambient_ci::run] run? true
[2025-09-16T09:48:26Z INFO ambient_ci::run] project rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5: running CI
[2025-09-16T09:48:26Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-09-16T09:48:26Z DEBUG ambient_ci::action] Plan::execute: CargoFetch {
sourcedir: "/tmp/.tmpPELNwz/src",
dependenciesdir: "/srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/dependencies",
}
SPAWN: argv=["env", "CARGO_HOME=/srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/dependencies", "cargo", "fetch", "--locked"]
cwd=/tmp/.tmpPELNwz/src (exists? true)
extra_env=[]
[2025-09-16T09:48:27Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpe22cV2/src.tar
[2025-09-16T09:48:27Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpe22cV2/deps.tar
[2025-09-16T09:48:30Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-09-16T09:48:30Z 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\"\nexport RUSTDOCFLAGS='-D warnings'\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-16T09:48:30Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-09-16T09:48:30Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmpe22cV2/ambient-execute-plan/run-ci
[2025-09-16T09:48:30Z DEBUG ambient_ci::run] copy OK: true
[2025-09-16T09:48:30Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmpe22cV2/executor.tar
[2025-09-16T09:48:42Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-09-16T09:48:42Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5, exists? true
[2025-09-16T09:48:42Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-09-16T09:48:42Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log OK
[2025-09-16T09:48:42Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-09-16T09:48:42Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-09-16T09:48:42Z 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-16T09:48:42Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-09-16T09:48:42Z 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-16T09:48:42Z DEBUG ambient_ci::qemu] run QEMU
[2025-09-16T09:48:42Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-09-16T09:48:42Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmpe6aBG3/.tmpql5pPb/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-09-16T09:48:42Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-09-16T09:48:42Z 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-16T09:48:42Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpce2HZi/meta-data
[2025-09-16T09:48:42Z DEBUG ambient_ci::cloud_init] write /tmp/.tmpce2HZi/user-data
[2025-09-16T09:48:43Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmpe6aBG3/.tmpql5pPb/console.log
[2025-09-16T09:48:43Z DEBUG ambient_ci::util] create file /srv/tmp/.tmpe6aBG3/.tmpql5pPb/console.log
[2025-09-16T09:48:43Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-09-16T09:48:43Z DEBUG ambient_ci::util] create file /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-09-16T09:48:43Z DEBUG ambient_ci::qemu] run QEMU: QemuArgs {
args: [
"-m",
"7629",
"-smp",
"cpus=4",
"-cpu",
"kvm64",
"-machine",
"type=q35,accel=kvm,usb=off",
"-uuid",
"a85c9de7-edc0-4e54-bead-112e5733582c",
"-boot",
"strict=on",
"-name",
"ambient-ci-vm",
"-rtc",
"base=utc,driftfix=slew",
"-display",
"none",
"-device",
"virtio-rng-pci",
"-serial",
"file:/srv/tmp/.tmpe6aBG3/.tmpql5pPb/console.log",
"-serial",
"file:/srv/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=/srv/tmp/.tmpe6aBG3/.tmpql5pPb/vars.fd",
"-drive",
"format=qcow2,if=virtio,file=/srv/tmp/.tmpe6aBG3/.tmpql5pPb/vm.qcow2",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmpe22cV2/executor.tar,readonly=on",
"-cdrom",
"/srv/tmp/.tmpe6aBG3/.tmpql5pPb/cloud_init.iso",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmpe22cV2/src.tar,readonly=on",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmpe22cV2/artifacts.tar",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmpe22cV2/cache.tar",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmpe22cV2/deps.tar,readonly=on",
"-nodefaults",
"-no-user-config",
],
}
[2025-09-16T09:53:41Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-09-16T09:53:41Z DEBUG ambient_ci::qemu] run log:
--------------------==========
[2025-09-16T09:48:52Z TRACE ambient_execute_plan] ambient-execute-plan starts
[2025-09-16T09:48:52Z DEBUG ambient_execute_plan] ambient-execute-plan starts
[2025-09-16T09:48:52Z INFO ambient_execute_plan] ambient-execute-plan starts
[2025-09-16T09:48:52Z WARN ambient_execute_plan] ambient-execute-plan starts
[2025-09-16T09:48:52Z 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"
export RUSTDOCFLAGS='-D warnings'
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-16T09:48:52Z DEBUG ambient_ci::action] Plan::execute: Mkdir {
pathname: "/workspace",
}
RUN: Action finished OK
RUN: Action Mkdir {
pathname: "/workspace/artifacts",
}
[2025-09-16T09:48:52Z 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-16T09:48:52Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
archive: "/dev/vdc",
directory: "/workspace/src",
}
[2025-09-16T09:48:52Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-09-16T09:48:53Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
archive: "/dev/vdf",
directory: "/workspace/deps",
}
[2025-09-16T09:48:53Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
archive: "/dev/vdf",
directory: "/workspace/deps",
}
[2025-09-16T09:48:53Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-09-16T09:49:11Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action TarExtract {
archive: "/dev/vde",
directory: "/workspace/cache",
}
[2025-09-16T09:49:11Z DEBUG ambient_ci::action] Plan::execute: TarExtract {
archive: "/dev/vde",
directory: "/workspace/cache",
}
[2025-09-16T09:49:11Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-09-16T09:50:34Z TRACE ambient_ci::vdrive] extraction OK
RUN: Action finished OK
RUN: Action Spawn {
argv: [
"find",
"/workspace",
"-maxdepth",
"2",
"-ls",
],
}
[2025-09-16T09:50:34Z 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 16 09:49 /workspace
2622199 4 drwxr-xr-x 3 root root 4096 Sep 16 09:48 /workspace/deps
2622200 0 -rw-r--r-- 1 root root 0 Sep 15 14:35 /workspace/deps/.package-cache-mutate
2622203 4 drwxr-xr-x 5 root root 4096 Sep 15 14:35 /workspace/deps/registry
2622201 120 -rw-r--r-- 1 root root 122880 Sep 16 09:48 /workspace/deps/.global-cache
2622202 0 -rw-r--r-- 1 root root 0 Sep 15 14:35 /workspace/deps/.package-cache
2621443 4 drwxr-xr-x 12 root root 4096 Sep 16 09:48 /workspace/src
2621499 4 -rw-r--r-- 1 root root 2653 Sep 16 09:48 /workspace/src/Cargo.toml
2622195 12 -rw-r--r-- 1 root root 9636 Sep 16 09:48 /workspace/src/CONTRIBUTING.md
2621500 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/.config
2621496 4 -rw-r--r-- 1 root root 1623 Sep 16 09:48 /workspace/src/build.rs
2621502 4 -rw-r--r-- 1 root root 426 Sep 16 09:48 /workspace/src/radicle-node.1.adoc
2621504 4 drwxr-xr-x 8 root root 4096 Sep 16 09:48 /workspace/src/.git
2622192 8 -rw-r--r-- 1 root root 7144 Sep 16 09:48 /workspace/src/rad-id.1.adoc
2621460 4 -rw-r--r-- 1 root root 2163 Sep 16 09:48 /workspace/src/VERSIONING.md
2621495 4 -rw-r--r-- 1 root root 3133 Sep 16 09:48 /workspace/src/README.md
2621615 12 -rw-r--r-- 1 root root 10770 Sep 16 09:48 /workspace/src/rad-patch.1.adoc
2622196 4 -rw-r--r-- 1 root root 79 Sep 16 09:48 /workspace/src/ARCHITECTURE.md
2621623 4 drwxr-xr-x 17 root root 4096 Sep 16 09:48 /workspace/src/crates
2621448 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/scripts
2621444 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/.radicle
2621467 8 -rw-r--r-- 1 root root 8167 Sep 16 09:48 /workspace/src/CHANGELOG.md
2621482 4 drwxr-xr-x 3 root root 4096 Sep 16 09:48 /workspace/src/debian
2621465 4 -rw-r--r-- 1 root root 42 Sep 16 09:48 /workspace/src/.envrc
2622193 4 -rw-r--r-- 1 root root 1203 Sep 16 09:48 /workspace/src/DCO
2622188 4 drwxr-xr-x 3 root root 4096 Sep 16 09:48 /workspace/src/.github
2622198 116 -rw-r--r-- 1 root root 117616 Sep 16 09:48 /workspace/src/Cargo.lock
2621616 4 drwxr-xr-x 5 root root 4096 Sep 16 09:48 /workspace/src/systemd
2621614 12 -rw-r--r-- 1 root root 9723 Sep 16 09:48 /workspace/src/LICENSE-APACHE
2621498 4 -rw-r--r-- 1 root root 101 Sep 16 09:48 /workspace/src/.gitignore
2622194 8 -rw-r--r-- 1 root root 5102 Sep 16 09:48 /workspace/src/flake.lock
2621464 4 -rw-r--r-- 1 root root 77 Sep 16 09:48 /workspace/src/rust-toolchain.toml
2621497 12 -rw-r--r-- 1 root root 10500 Sep 16 09:48 /workspace/src/flake.nix
2621468 4 -rw-r--r-- 1 root root 571 Sep 16 09:48 /workspace/src/git-remote-rad.1.adoc
2621466 4 -rw-r--r-- 1 root root 61 Sep 16 09:48 /workspace/src/.env.seed
2621503 8 -rw-r--r-- 1 root root 5432 Sep 16 09:48 /workspace/src/deny.toml
2621458 8 -rw-r--r-- 1 root root 7645 Sep 16 09:48 /workspace/src/HACKING.md
2621447 4 -rw-r--r-- 1 root root 1079 Sep 16 09:48 /workspace/src/LICENSE-MIT
2621463 8 -rw-r--r-- 1 root root 8023 Sep 16 09:48 /workspace/src/rad.1.adoc
2621469 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/build
2622197 4 -rw-r--r-- 1 root root 1083 Sep 16 09:48 /workspace/src/.gitsigners
2621459 4 -rw-r--r-- 1 root root 30 Sep 16 09:48 /workspace/src/.dockerignore
2621461 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/src/.cargo
2621442 4 drwxr-xr-x 2 root root 4096 Sep 16 09:48 /workspace/artifacts
2648928 4 drwxr-xr-x 6 root root 4096 Sep 16 08:29 /workspace/cache
2665369 4 -rw-r--r-- 1 root root 218 Sep 16 08:29 /workspace/cache/.rustdoc_fingerprint.json
2753975 4 drwxr-xr-x 23 root root 4096 Sep 16 08:29 /workspace/cache/doc
2648930 4 drwxr-xr-x 7 root root 4096 Sep 16 08:26 /workspace/cache/debug
2648929 4 drwxr-xr-x 2 root root 4096 Sep 16 08:23 /workspace/cache/tmp
2668750 4 -rw-r--r-- 1 root root 1032 Sep 16 08:25 /workspace/cache/.rustc_info.json
2665370 4 drwxr-xr-x 7 root root 4096 Sep 16 08:33 /workspace/cache/release
RUN: Action finished OK
RUN: Action CargoFmt
[2025-09-16T09:50:34Z 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-16T09:50:36Z 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.10.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)
Compiling radicle-cli v0.16.0 (/workspace/src/crates/radicle-cli)
Compiling radicle-node v0.15.0 (/workspace/src/crates/radicle-node)
Checking radicle-term v0.15.0 (/workspace/src/crates/radicle-term)
Checking radicle-crypto v0.13.0 (/workspace/src/crates/radicle-crypto)
Checking radicle-systemd v0.10.0 (/workspace/src/crates/radicle-systemd)
Compiling radicle-remote-helper v0.13.0 (/workspace/src/crates/radicle-remote-helper)
Checking radicle-cob v0.16.0 (/workspace/src/crates/radicle-cob)
Checking radicle v0.19.0 (/workspace/src/crates/radicle)
Checking radicle-fetch v0.15.0 (/workspace/src/crates/radicle-fetch)
Checking radicle-cli-test v0.13.0 (/workspace/src/crates/radicle-cli-test)
Checking radicle-schemars v0.5.0 (/workspace/src/crates/radicle-schemars)
Checking radicle-protocol v0.3.0 (/workspace/src/crates/radicle-protocol)
Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.52s
RUN: Action finished OK
RUN: Action CargoBuild
[2025-09-16T09:50:52Z 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.10.0 (/workspace/src/crates/radicle-ssh)
Compiling radicle-dag v0.10.0 (/workspace/src/crates/radicle-dag)
Compiling radicle-signals v0.11.0 (/workspace/src/crates/radicle-signals)
Compiling radicle-node v0.15.0 (/workspace/src/crates/radicle-node)
Compiling radicle-crypto v0.13.0 (/workspace/src/crates/radicle-crypto)
Compiling radicle-cli v0.16.0 (/workspace/src/crates/radicle-cli)
Compiling radicle-term v0.15.0 (/workspace/src/crates/radicle-term)
Compiling radicle-systemd v0.10.0 (/workspace/src/crates/radicle-systemd)
Compiling radicle-cob v0.16.0 (/workspace/src/crates/radicle-cob)
Compiling radicle-remote-helper v0.13.0 (/workspace/src/crates/radicle-remote-helper)
Compiling radicle v0.19.0 (/workspace/src/crates/radicle)
Compiling radicle-fetch v0.15.0 (/workspace/src/crates/radicle-fetch)
Compiling radicle-protocol v0.3.0 (/workspace/src/crates/radicle-protocol)
Compiling radicle-cli-test v0.13.0 (/workspace/src/crates/radicle-cli-test)
Compiling radicle-schemars v0.5.0 (/workspace/src/crates/radicle-schemars)
Finished `dev` profile [unoptimized + debuginfo] target(s) in 39.52s
RUN: Action finished OK
RUN: Action CargoTest
[2025-09-16T09:51:31Z 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 2.17s
Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle-51334f29bf16c6df)
running 237 tests
test cob::cache::migrations::_2::tests::test_migration_2 ... ok
test canonical::formatter::test::ascii_control_characters ... ok
test canonical::formatter::test::ordered_nested_object ... ok
test canonical::formatter::test::securesystemslib_asserts ... ok
test cob::cache::migrations::_2::tests::test_patch_json_deserialization ... ok
test cob::common::test::test_color ... ok
test cob::cache::tests::test_check_version ... ok
test cob::common::test::test_emojis ... ok
test cob::cache::tests::test_migrate_to ... 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_remove_delegate_concurrent ... ok
test cob::identity::test::test_identity_reject_concurrent ... ok
test cob::identity::test::test_identity_updates ... ok
test cob::identity::test::test_identity_update_rejected ... 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_identity_updates_concurrent ... 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::identity::test::test_identity_updates_concurrent_outdated ... ok
test cob::issue::test::test_invalid_actions ... ok
test cob::issue::test::test_invalid_tx ... ok
test cob::issue::test::test_concurrency ... ok
test cob::issue::test::test_invalid_tx_reference ... ok
test cob::issue::test::test_invalid_cob ... ok
test cob::issue::test::test_issue_all ... ok
test cob::issue::test::test_issue_create_and_assign ... 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_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_multilines ... ok
test cob::issue::test::test_issue_label ... ok
test cob::issue::test::test_issue_state_serde ... ok
test cob::issue::test::test_ordering ... ok
test cob::patch::actions::test::test_review_edit ... ok
test cob::issue::test::test_issue_edit_description ... ok
test cob::issue::test::test_issue_react ... ok
test cob::patch::cache::tests::test_get ... ok
test cob::patch::cache::tests::test_is_empty ... ok
test cob::issue::test::test_issue_reply ... ok
test cob::patch::cache::tests::test_find_by_revision ... ok
test cob::patch::cache::tests::test_list ... ok
test cob::patch::encoding::review::test::test_review_deserialize_summary_migration_null_summary ... ok
test cob::patch::encoding::review::test::test_review_deserialize_summary_migration_with_summary ... ok
test cob::patch::encoding::review::test::test_review_deserialize_summary_migration_without_summary ... ok
test cob::patch::encoding::review::test::test_review_deserialize_summary_v2 ... ok
test cob::patch::encoding::review::test::test_review_summary ... ok
test cob::patch::test::test_json ... ok
test cob::patch::test::test_json_serialization ... ok
test cob::patch::cache::tests::test_list_by_status ... 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::cache::tests::test_counts ... ok
test cob::patch::test::test_patch_redact ... ok
test cob::patch::cache::tests::test_remove ... ok
test cob::patch::test::test_patch_review_comment ... ok
test cob::patch::test::test_patch_review ... 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_remove_summary ... 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_review_edit_comment ... ok
test cob::stream::tests::test_all_from ... ok
test cob::patch::test::test_patch_review_revision_redact ... ok
test cob::stream::tests::test_all_from_until ... ok
test cob::stream::tests::test_all_until ... ok
test cob::stream::tests::test_regression_from_until ... ok
test cob::thread::tests::test_comment_edit_missing ... ok
test cob::thread::tests::test_comment_edit_redacted ... ok
test cob::stream::tests::test_from_until ... ok
test cob::thread::tests::test_comment_redact_missing ... ok
test cob::thread::tests::test_edit_comment ... ok
test cob::thread::tests::test_duplicate_comments ... ok
test cob::thread::tests::test_redact_comment ... ok
test git::canonical::quorum::test::merge_base_commutative ... ok
test git::canonical::quorum::test::test_merge_bases ... ok
test cob::thread::tests::test_timeline ... ok
test git::canonical::rules::tests::test_deserialization ... ok
test git::canonical::rules::tests::test_deserialize_extensions ... ok
test git::canonical::rules::tests::test_order ... ok
test git::canonical::rules::tests::test_roundtrip ... ok
test git::canonical::rules::tests::test_canonical ... ok
test git::canonical::rules::tests::test_rule_validate_success ... ok
test git::canonical::rules::tests::test_special_branches ... ok
test git::canonical::tests::test_commit_quorum_fork_of_a_fork ... ok
test git::canonical::tests::test_commit_quorum_forked_merge_commits ... ok
test git::canonical::tests::test_commit_quorum_groups ... ok
test git::canonical::tests::test_commit_quorum_linear ... ok
test git::canonical::tests::test_commit_quorum_merges ... ok
test git::canonical::tests::test_commit_quorum_single ... ok
test git::canonical::tests::test_commit_quorum_three_way_fork ... ok
test git::canonical::tests::test_commit_quorum_two_way_fork ... ok
test git::canonical::tests::test_quorum_different_types ... ok
test git::canonical::rules::tests::test_rule_validate_failures ... ok
test git::canonical::tests::test_tag_quorum ... 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::patch::test::test_patch_update ... 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::doc::update::test::test_can_update_crefs ... ok
test identity::doc::update::test::test_cannot_include_default_branch_rule ... ok
test identity::doc::update::test::test_default_branch_rule_exists_after_verification ... 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 git::canonical::tests::test_quorum_properties ... 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_remove_nothing ... ok
test node::address::store::test::test_node_aliases ... ok
test node::config::test::partial ... ok
test node::features::test::test_operations ... ok
test node::db::test::test_version ... 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_policies ... ok
test node::policy::store::test::test_node_aliases ... 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_duplicate ... ok
test node::routing::test::test_insert_existing_updated_time ... ok
test node::routing::test::test_insert_and_remove ... ok
test node::routing::test::test_len ... 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::all_synced_nodes_are_preferred_seeds ... ok
test node::routing::test::test_prune ... ok
test node::sync::announce::test::announcer_adapts_target_to_reach ... ok
test node::sync::announce::test::announcer_preferred_seeds_or_replica_factor ... 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_reached_preferred_seeds ... ok
test cob::thread::tests::prop_ordering ... ok
test node::sync::announce::test::announcer_timed_out ... ok
test node::sync::announce::test::announcer_with_replication_factor_zero_and_preferred_seeds ... ok
test node::sync::announce::test::announcer_synced_with_unknown_node ... ok
test node::sync::announce::test::construct_node_appears_in_multiple_input_sets ... ok
test node::sync::announce::test::construct_only_preferred_seeds_provided ... ok
test node::sync::announce::test::invariant_progress_should_match_state ... ok
test node::sync::announce::test::cannot_construct_announcer ... ok
test node::sync::announce::test::local_node_in_preferred_seeds ... ok
test node::sync::announce::test::local_node_in_synced_set ... ok
test node::sync::announce::test::local_node_only_in_all_sets_results_in_no_seeds_error ... ok
test node::sync::announce::test::local_node_in_unsynced_set ... ok
test node::sync::announce::test::synced_with_local_node_is_ignored ... ok
test node::sync::announce::test::preferred_seeds_already_synced ... ok
test node::sync::announce::test::timed_out_after_reaching_success ... ok
test node::sync::announce::test::synced_with_same_node_multiple_times ... ok
test node::sync::announce::test::local_node_in_multiple_sets ... 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::ignores_duplicates_and_local_node ... ok
test node::sync::fetch::test::could_not_reach_target ... 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::test::ensure_replicas_construction ... ok
test node::sync::fetch::test::reaches_target_of_preferred_seeds ... ok
test node::sync::test::replicas_constrain_to ... ok
test node::sync::fetch::test::reaches_target_of_replicas ... 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::test::canonicalize_home ... ok
test profile::test::test_config ... ok
test rad::tests::test_fork ... ok
test rad::tests::test_checkout ... ok
test serde_ext::test::test_localtime ... ok
test serde_ext::test::test_localtime_ext ... ok
test rad::tests::test_init ... ok
test storage::git::tests::test_references_of ... 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::git::tests::test_remote_refs ... 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 profile::config::test::schema ... ok
test storage::refs::tests::test_rid_verification ... ok
test identity::doc::test::prop_encode_decode ... ok
test result: ok. 237 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.15s
Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cli-621b4b410a8c352b)
running 14 tests
test commands::patch::review::builder::tests::test_review_comments_basic ... ok
test commands::inspect::test::test_tree ... ok
test commands::patch::review::builder::tests::test_review_comments_split_hunk ... ok
test git::ddiff::tests::diff_encode_decode_ddiff_hunk ... ok
test commands::patch::review::builder::tests::test_review_comments_multiline ... 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 terminal::patch::test::test_edit_display_message ... ok
test terminal::patch::test::test_update_display_message ... ok
test terminal::patch::test::test_create_display_message ... ok
test commands::patch::review::builder::tests::test_review_comments_before ... ok
test result: ok. 13 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.03s
Running unittests src/main.rs (/workspace/cache/debug/deps/rad-90c5a176eb08002b)
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-c121b642be9ff500)
running 104 tests
test framework_home ... ok
test git_push_and_fetch ... ok
test git_push_amend ... ok
test git_push_canonical_annotated_tags ... ok
test git_push_canonical_lightweight_tags ... ok
test git_push_diverge ... ok
test rad_auth ... ok
test rad_auth_errors ... ok
test git_tag ... FAILED
test git_push_rollback ... ok
test rad_block ... ok
test rad_checkout ... ok
test git_push_converge ... ok
test rad_clone ... ok
test rad_clone_bare ... ok
test rad_clean ... ok
test rad_clone_all ... ok
test rad_clone_unknown ... ok
test rad_clone_directory ... ok
test rad_clone_connect ... ok
test rad_cob_log ... ok
test rad_cob_migrate ... ok
test rad_cob_multiset ... ok
test rad_clone_partial_fail ... ok
test rad_cob_operations ... ok
test rad_cob_update_identity ... ok
test rad_cob_show ... ok
test rad_cob_update ... ok
test rad_diff ... ok
test rad_config ... ok
test rad_id_collaboration ... ignored, slow
test rad_id ... ok
test rad_id_conflict ... ok
test rad_id_private ... ok
test rad_fork ... ok
test rad_fetch ... ok
test rad_id_multi_delegate ... ok
test rad_id_unknown_field ... ok
test rad_id_update_delete_field ... ok
test rad_init ... ignored, part of many other tests
test rad_id_threshold ... ok
test rad_id_threshold_soft_fork ... ok
test rad_init_bare ... ok
test rad_init_detached_head ... ok
test rad_init_existing ... ok
test rad_init_existing_bare ... ok
test rad_init_no_git ... ok
test rad_init_no_seed ... ok
test rad_init_private ... ok
test rad_init_private_no_seed ... ok
test rad_inbox ... ok
test rad_init_private_clone ... ok
test rad_init_private_clone_seed ... ok
test rad_init_sync_not_connected ... ok
test rad_init_private_seed ... ok
test rad_init_with_existing_remote ... ok
test rad_init_sync_preferred ... ok
test rad_inspect ... ok
test rad_issue ... ok
test rad_merge_after_update ... ok
test rad_merge_no_ff ... ok
test rad_merge_via_push ... ok
test rad_init_sync_and_clone ... ok
test rad_node_connect ... ok
test rad_init_sync_timeout ... ok
test rad_node ... ok
test rad_node_connect_without_address ... ok
test rad_patch_change_base ... ok
test rad_patch_ahead_behind ... ok
test rad_patch ... ok
test rad_patch_checkout ... ok
test rad_patch_checkout_revision ... ok
test rad_patch_detached_head ... ok
test rad_patch_diff ... ok
test rad_patch_draft ... ok
test rad_patch_checkout_force ... ok
test rad_patch_edit ... ok
test rad_patch_fetch_2 ... FAILED
test rad_patch_merge_draft ... ok
test rad_patch_fetch_1 ... ok
test rad_patch_revert_merge ... ok
test rad_patch_update ... ok
test rad_patch_open_explore ... ok
test rad_patch_delete ... ok
test rad_patch_via_push ... FAILED
test rad_publish ... ok
test rad_review_by_hunk ... ok
test rad_seed_and_follow ... ok
test rad_patch_pull_update ... ok
test rad_self ... ok
test rad_push_and_pull_patches ... ok
test rad_remote ... ok
test rad_seed_many ... ok
test rad_unseed ... ok
test rad_unseed_many ... ok
test rad_sync_without_node ... ok
test rad_warn_old_nodes ... ok
test test_clone_without_seeds ... ok
test rad_watch ... ok
test rad_sync ... ok
test test_cob_deletion ... ok
test test_cob_replication ... ok
test rad_workflow ... ok
test test_replication_via_seed ... ok
failures:
---- git_tag stdout ----
1758016331 test: Using PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-nXtOYX/work/alice"]
1758016331 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["init", "--name", "heartwood", "--description", "Radicle Heartwood Protocol & Stack", "--no-confirm", "--public", "-v"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016331 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016331 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016331 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016331 git_tag node: Opening policy database..
1758016331 git_tag node: Default seeding policy set to 'block (all)'
1758016331 git_tag node: Initializing service (Test)..
1758016331 git_tag node: Opening node database..
1758016331 git_tag node: Address book is empty. Adding bootstrap nodes..
1758016331 git_tag node: 0 nodes added to address book
1758016331 git_tag service: Init @1758016331522
1758016331 git_tag service: Empty refs database, populating from storage..
1758016331 git_tag service: Saved local sync status for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji..
1758016331 git_tag service: Adding refs announcement for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to historical gossip messages..
1758016332 git_tag service: Not enough available peers to connect to (available=0, wanted=8)
1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> node: Listening on 0.0.0.0:36645..
1758016332 git_tag node: Binding control socket /tmp/radicle-nXtOYX/home/alice/.radicle/node/control.sock..
1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> node: Running node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi in /tmp/radicle-nXtOYX/home/alice/.radicle..
1758016332 git_tag node: Opening policy database..
1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Control thread listening on socket..
1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <signals> node: Signal notifications channel error: receiving on an empty and disconnected channel
1758016332 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received command QueryState(..)
1758016332 git_tag node: Default seeding policy set to 'block (all)'
1758016332 git_tag node: Initializing service (Test)..
1758016332 git_tag node: Opening node database..
1758016333 git_tag node: Address book is empty. Adding bootstrap nodes..
1758016333 git_tag node: 0 nodes added to address book
1758016333 git_tag service: Init @1758016332016
1758016333 git_tag service: Empty refs database, populating from storage..
1758016333 git_tag service: Not enough available peers to connect to (available=0, wanted=8)
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> node: Listening on 0.0.0.0:44055..
1758016333 git_tag node: Binding control socket /tmp/radicle-nXtOYX/home/bob/.radicle/node/control.sock..
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> node: Running node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk in /tmp/radicle-nXtOYX/home/bob/.radicle..
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Control thread listening on socket..
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <signals> node: Signal notifications channel error: receiving on an empty and disconnected channel
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Connect(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi, 0.0.0.0:36645, ConnectOptions { persistent: false, timeout: 30s })
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Connecting to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:36645)..
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Attempted connection to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:36645)
1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Inbound connection from 127.0.0.1:48342 (fd=203)..
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Registering outbound transport for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (fd=202)..
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Outbound peer resource registered for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with id=2 (fd=202)
1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Accepted inbound connection from 127.0.0.1:48342 (fd=203)..
1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Inbound peer resource registered with id=2 (fd=203)
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Session established with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (id=2) (fd=202) (outbound)
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Connected to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:36645) (Outbound)
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Subscribing to messages since timestamp 1757929933615..
1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Session established with z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (id=2) (fd=203) (inbound)
1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Connected to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (127.0.0.1:48342) (Inbound)
1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Subscribing to messages since timestamp 1757929933615..
1758016333 test: Node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi has converged
1758016333 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received node announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 address(es) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1758016332016)
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received node announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 0 address(es) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1758016331522)
1758016333 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016333 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1758016332016)
1758016333 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1758016331522)
1758016333 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016333 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Address store entry for node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi updated at 1758016331522
1758016334 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Address store entry for node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk updated at 1758016332016
1758016334 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1758016332017)
1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1758016331524)
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1758016331524)
1758016334 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1758016332017)
1758016334 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1758016334 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received subscription filter from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1758016334 test: Node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk has converged
1758016335 test: Using PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-nXtOYX/work/bob"]
1758016335 test: rad-clone.md: Running `/workspace/cache/debug/rad` with ["clone", "rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji", "--scope", "followed"] in `/tmp/radicle-nXtOYX/work/bob`..
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"status"}` on control socket
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"seed","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","scope":"followed"}` on control socket
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Seed(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, followed)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending subscription filter to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"seeds","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"}` on control socket
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Found 1 connected seed(s) and 0 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"session","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"}` on control socket
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"fetch","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi","timeout":{"secs":9,"nanos":0}}` on control socket
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Fetch(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [outbound connected] (all remotes)..
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Opened new stream with id 12 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received `open` command for stream 12 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Worker processing incoming fetch for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk on stream 12..
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> worker: z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk cloning from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 12..
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched rad/id (18ms)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched data for 1 remote(s) (37ms)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched data refs for 1 remotes (50ms)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received `end-of-file` on stream 12 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <upload-pack> worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Upload pack finished (62ms)
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Upload process on stream 12 exited with result Ok(())
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received fetch result from worker for stream 12, remote z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk: Responder { rid: Some(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)), result: Ok(()) }
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Stream 12 of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk closing with 0 byte(s) sent and 0 byte(s) received
1758016335 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Received `close` command for stream 12 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Stream 12 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Validated 1 remote(s) (52ms)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Applied updates (54ms)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Finished clone of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (67ms)
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: refs/rad/id -> 0656c217f917c3e06234771e9ecae53aba5e173e
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: refs/heads/master -> f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: HEAD -> refs/heads/master
1758016335 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Received fetch result from worker for stream 12, remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi: Initiator { rid: RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), result: Ok(FetchResult { updated: [Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id"), oid: Oid(0656c217f917c3e06234771e9ecae53aba5e173e) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs"), oid: Oid(99c549702e2bcfe02b0e68d4a2224fb7a1524529) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0656c217f917c3e06234771e9ecae53aba5e173e"), oid: Oid(0656c217f917c3e06234771e9ecae53aba5e173e) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master"), oid: Oid(f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354) }, Skipped { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id"), oid: Oid(0656c217f917c3e06234771e9ecae53aba5e173e) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root"), oid: Oid(0656c217f917c3e06234771e9ecae53aba5e173e) }], canonical: UpdatedCanonicalRefs { inner: {Qualified(RefString("refs/heads/master")): Oid(f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354)} }, namespaces: {PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi)}, clone: true, doc: DocAt { commit: Oid(0656c217f917c3e06234771e9ecae53aba5e173e), blob: Oid(d96f425412c9f8ad5d9a9a05c9831d0728e2338d), doc: Doc { version: Version(1), payload: {PayloadId(TypeName("xyz.radicle.project")): Payload { value: Object {"defaultBranch": String("master"), "description": String("Radicle Heartwood Protocol & Stack"), "name": String("heartwood")} }}, delegates: Delegates(NonEmpty { head: Did("did:key:z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"), tail: [] }), threshold: Threshold(1), visibility: Public } } }) }
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Found existing fetch request from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi, sending result..
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sent fetch result for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi..
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully
1758016336 test: rad-clone.md: Running `cd /tmp/radicle-nXtOYX/work/bob/heartwood`..
1758016336 test: rad-clone.md: Running `ls` with [] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..
1758016336 test: rad-clone.md: Running `cat` with ["README"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..
1758016336 test: rad-clone.md: Running `git` with ["status"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..
1758016336 test: rad-clone.md: Running `git` with ["branch", "--remotes"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..
1758016336 test: rad-clone.md: Running `git` with ["remote", "-v"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..
1758016336 test: rad-clone.md: Running `git` with ["log", "-n", "1"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..
1758016336 test: rad-clone.md: Running `/workspace/cache/debug/rad` with ["ls", "--seeded"] in `/tmp/radicle-nXtOYX/work/bob/heartwood`..
1758016336 test: Using PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-nXtOYX"]
1758016336 test: git-tag.md: Running `touch` with ["LICENSE"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016336 test: git-tag.md: Running `git` with ["add", "LICENSE"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..99c5497 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0656c217f917c3e06234771e9ecae53aba5e173e for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..f2de534 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Updating and announcing inventory for cloned repository rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji..
1758016336 test: git-tag.md: Running `git` with ["commit", "-am", "Add LICENSE"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016336 test: git-tag.md: Running `git` with ["tag", "v1.0", "-a", "-m", "Release v1.0"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016336 test: git-tag.md: Running `git` with ["push", "rad", "v1.0"] in `/tmp/radicle-nXtOYX/work/alice`..
1758016336 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"status"}` on control socket
1758016336 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"seeds","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"}` on control socket
1758016336 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)
1758016336 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Found 0 connected seed(s) and 1 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
thread 'git_tag' panicked at crates/radicle-cli-test/src/lib.rs:489:36:
--- Expected
++++ actual: stderr
1 - ✓ Synced with 1 seed(s)
1 + No seeds found for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji.
2 2 | To rad://z42hL2jL4XNk6K8oHQaSWfMgCL7ji/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
3 3 | * [new tag] v1.0 -> v1.0
Exit status: 0
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
1758016336 test: Node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk shutting down..
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"shutdown"}` on control socket
1758016336 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Shutdown requested..
1758016337 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 1 item(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1758016336239)
1758016337 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1758016336240)
1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Disconnecting peer with id=2: connection reset
1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Transport handover for disconnecting peer with id=2 (fd=203)
1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Disconnected from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (connection reset)
1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Dropping peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk..
1758016337 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> pool: Worker pool shutting down..
1758016337 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> node: Node shutdown completed for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1758016337 test: Node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi shutting down..
1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"shutdown"}` on control socket
1758016337 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Shutdown requested..
1758016338 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> pool: Worker pool shutting down..
1758016338 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> node: Node shutdown completed for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
---- rad_patch_fetch_2 stdout ----
1758016387 test: Using PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-PjI6mV/work/alice"]
1758016387 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["init", "--name", "heartwood", "--description", "Radicle Heartwood Protocol & Stack", "--no-confirm", "--public", "-v"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: Using PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-PjI6mV/work/alice"]
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["checkout", "-b", "alice/1", "-q"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["commit", "--allow-empty", "-m", "Changes #1", "-q"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["push", "rad", "-o", "patch.message=Changes", "HEAD:refs/patches"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["checkout", "master", "-q"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["branch", "-D", "alice/1", "-q"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["update-ref", "-d", "refs/remotes/rad/alice/1"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["update-ref", "-d", "refs/remotes/rad/patches/5e2dedcc5d515fcbc1cca483d3376609fe889bfb"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["gc", "--prune=now"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["branch", "-r"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["pull"] in `/tmp/radicle-PjI6mV/work/alice`..
1758016387 test: rad-patch-fetch-2.md: Running `git` with ["branch", "-r"] in `/tmp/radicle-PjI6mV/work/alice`..
thread 'rad_patch_fetch_2' panicked at crates/radicle-cli-test/src/lib.rs:491:36:
--- Expected
++++ actual: stdout
1 - rad/HEAD -> rad/master
2 1 | rad/master
3 2 | rad/patches/5e2dedcc5d515fcbc1cca483d3376609fe889bfb
Exit status: 0
---- rad_patch_via_push stdout ----
1758016396 test: Using PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-m1WeYF/work/alice"]
1758016396 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["init", "--name", "heartwood", "--description", "Radicle Heartwood Protocol & Stack", "--no-confirm", "--public", "-v"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: Using PATH ["/workspace/cache/debug", "/workspace/deps/bin", "/root/.cargo/bin", "/usr/local/sbin", "/usr/local/bin", "/usr/sbin", "/usr/bin", "/sbin", "/bin", "/tmp/radicle-m1WeYF/work/alice"]
1758016396 test: rad-patch-via-push.md: Running `git` with ["checkout", "-b", "feature/1"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-patch-via-push.md: Running `git` with ["commit", "-a", "-m", "Add things", "-q", "--allow-empty"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-patch-via-push.md: Running `git` with ["push", "-o", "patch.message=Add things #1", "-o", "patch.message=See commits for details.", "rad", "HEAD:refs/patches"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-patch-via-push.md: Running `/workspace/cache/debug/rad` with ["patch", "show", "6035d2f582afbe01ff23ea87528ae523d76875b6"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-patch-via-push.md: Running `git` with ["branch", "-vv"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-patch-via-push.md: Running `git` with ["status", "--short", "--branch"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-patch-via-push.md: Running `git` with ["fetch"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-patch-via-push.md: Running `git` with ["push"] in `/tmp/radicle-m1WeYF/work/alice`..
1758016396 test: rad-patch-via-push.md: Running `git` with ["show-ref"] in `/tmp/radicle-m1WeYF/work/alice`..
thread 'rad_patch_via_push' panicked at crates/radicle-cli-test/src/lib.rs:491:36:
--- Expected
++++ actual: stdout
1 1 | 42d894a83c9c356552a57af09ccdbd5587a99045 refs/heads/feature/1
2 2 | f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 refs/heads/master
3 - f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 refs/remotes/rad/HEAD
4 3 | f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 refs/remotes/rad/master
5 4 | 42d894a83c9c356552a57af09ccdbd5587a99045 refs/remotes/rad/patches/6035d2f582afbe01ff23ea87528ae523d76875b6
Exit status: 0
failures:
git_tag
rad_patch_fetch_2
rad_patch_via_push
test result: FAILED. 99 passed; 3 failed; 2 ignored; 0 measured; 0 filtered out; finished in 121.03s
error: test failed, to rerun pass `-p radicle-cli --test commands`
ERROR: Action failed: command failed: ["cargo", "test", "--offline", "--locked", "--workspace"]
ERROR: command failed: ["cargo", "test", "--offline", "--locked", "--workspace"]
EXIT CODE: 1
====================
[2025-09-16T09:53:42Z DEBUG ambient_ci::qemu] QEMU exit code 1
ERROR: failed to run actions in QEMU
<empty log>