Radicle repository id rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5
[2025-10-06T14:40:28Z 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
sr0 11:0 1 370K 0 rom
vda 254:0 0 100G 0 disk
├─vda1 254:1 0 953M 0 part
└─vda2 254:2 0 99.1G 0 part /
vdb 254:16 0 6.1M 1 disk
vdc 254:32 0 29.8M 1 disk
vdd 254:48 0 9.3G 0 disk
vde 254:64 0 46.6G 0 disk
vdf 254:80 0 809.9M 1 disk
INFO: Extracting tar archive from /dev/vdb
drwxr-xr-x _rad/_rad 0 2025-10-06 14:32 ./
-rw-r--r-- _rad/_rad 2450 2025-10-06 14:32 ./plan.yaml
-rwxr-xr-x _rad/_rad 6410400 2025-10-06 14:32 ./run-ci
INFO: Extracted files:
4587530 4 drwxr-xr-x 2 1001 1001 4096 Oct 6 14:32 .
4587531 4 -rw-r--r-- 1 1001 1001 2450 Oct 6 14:32 ./plan.yaml
4587532 6264 -rwxr-xr-x 1 1001 1001 6410400 Oct 6 14:32 ./run-ci
INFO: Running run-ci from /dev/vdb
================================ BEGIN ================================
[2025-10-06T14:32:57Z TRACE ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-06T14:32:57Z DEBUG ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-06T14:32:57Z INFO ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-06T14:32:57Z WARN ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-06T14:32:57Z ERROR ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 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
[2025-10-06T14:32:57Z DEBUG ambient_ci::plan] RUN: Action Mkdir {
pathname: "/workspace",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::action] Plan::execute: plan=Mkdir {
pathname: "/workspace",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:32:57Z DEBUG ambient_ci::plan] RUN: Action Mkdir {
pathname: "/workspace/artifacts",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::action] Plan::execute: plan=Mkdir {
pathname: "/workspace/artifacts",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:32:58Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:32:58Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
archive: "/dev/vdc",
directory: "/workspace/src",
}
[2025-10-06T14:32:58Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
archive: "/dev/vdc",
directory: "/workspace/src",
}
[2025-10-06T14:32:58Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:32:58Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-10-06T14:32:59Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-06T14:32:59Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:32:59Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
archive: "/dev/vdf",
directory: "/workspace/deps",
}
[2025-10-06T14:32:59Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
archive: "/dev/vdf",
directory: "/workspace/deps",
}
[2025-10-06T14:32:59Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:32:59Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-10-06T14:33:28Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-06T14:33:28Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:33:28Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
archive: "/dev/vde",
directory: "/workspace/cache",
}
[2025-10-06T14:33:28Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
archive: "/dev/vde",
directory: "/workspace/cache",
}
[2025-10-06T14:33:28Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:33:28Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-10-06T14:36:18Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-06T14:36:18Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:36:18Z DEBUG ambient_ci::plan] RUN: Action Spawn {
argv: [
"find",
"/workspace",
"-maxdepth",
"2",
"-ls",
],
}
[2025-10-06T14:36:18Z DEBUG ambient_ci::action] Plan::execute: plan=Spawn {
argv: [
"find",
"/workspace",
"-maxdepth",
"2",
"-ls",
],
}
[2025-10-06T14:36:18Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["find", "/workspace", "-maxdepth", "2", "-ls"]
cwd=/workspace/src (exists? true)
2621441 4 drwxr-xr-x 6 root root 4096 Oct 6 14:33 /workspace
2622157 4 drwxr-xr-x 3 root root 4096 Oct 6 14:31 /workspace/deps
2622158 0 -rw-r--r-- 1 root root 0 Sep 22 16:46 /workspace/deps/.package-cache-mutate
2622161 4 drwxr-xr-x 5 root root 4096 Sep 22 16:46 /workspace/deps/registry
2622159 128 -rw-r--r-- 1 root root 131072 Oct 6 14:31 /workspace/deps/.global-cache
2622160 0 -rw-r--r-- 1 root root 0 Sep 22 16:46 /workspace/deps/.package-cache
2621443 4 drwxr-xr-x 12 root root 4096 Oct 6 14:31 /workspace/src
2621499 4 -rw-r--r-- 1 root root 2653 Oct 6 14:31 /workspace/src/Cargo.toml
2622153 12 -rw-r--r-- 1 root root 9636 Oct 6 14:31 /workspace/src/CONTRIBUTING.md
2621500 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/.config
2621496 4 -rw-r--r-- 1 root root 1623 Oct 6 14:31 /workspace/src/build.rs
2621502 4 -rw-r--r-- 1 root root 426 Oct 6 14:31 /workspace/src/radicle-node.1.adoc
2621504 4 drwxr-xr-x 8 root root 4096 Oct 6 14:31 /workspace/src/.git
2622150 8 -rw-r--r-- 1 root root 7144 Oct 6 14:31 /workspace/src/rad-id.1.adoc
2621460 4 -rw-r--r-- 1 root root 2163 Oct 6 14:31 /workspace/src/VERSIONING.md
2621495 4 -rw-r--r-- 1 root root 3133 Oct 6 14:31 /workspace/src/README.md
2621551 12 -rw-r--r-- 1 root root 10975 Oct 6 14:31 /workspace/src/rad-patch.1.adoc
2622154 4 -rw-r--r-- 1 root root 79 Oct 6 14:31 /workspace/src/ARCHITECTURE.md
2621559 4 drwxr-xr-x 17 root root 4096 Oct 6 14:31 /workspace/src/crates
2621448 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/scripts
2621444 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/.radicle
2621467 16 -rw-r--r-- 1 root root 14022 Oct 6 14:31 /workspace/src/CHANGELOG.md
2621482 4 drwxr-xr-x 3 root root 4096 Oct 6 14:31 /workspace/src/debian
2621465 4 -rw-r--r-- 1 root root 42 Oct 6 14:31 /workspace/src/.envrc
2622151 4 -rw-r--r-- 1 root root 1203 Oct 6 14:31 /workspace/src/DCO
2622146 4 drwxr-xr-x 3 root root 4096 Oct 6 14:31 /workspace/src/.github
2622156 124 -rw-r--r-- 1 root root 122985 Oct 6 14:31 /workspace/src/Cargo.lock
2621552 4 drwxr-xr-x 5 root root 4096 Oct 6 14:31 /workspace/src/systemd
2621550 12 -rw-r--r-- 1 root root 9723 Oct 6 14:31 /workspace/src/LICENSE-APACHE
2621498 4 -rw-r--r-- 1 root root 101 Oct 6 14:31 /workspace/src/.gitignore
2622152 8 -rw-r--r-- 1 root root 5102 Oct 6 14:31 /workspace/src/flake.lock
2621464 4 -rw-r--r-- 1 root root 77 Oct 6 14:31 /workspace/src/rust-toolchain.toml
2621497 12 -rw-r--r-- 1 root root 10819 Oct 6 14:31 /workspace/src/flake.nix
2621468 4 -rw-r--r-- 1 root root 571 Oct 6 14:31 /workspace/src/git-remote-rad.1.adoc
2621466 4 -rw-r--r-- 1 root root 61 Oct 6 14:31 /workspace/src/.env.seed
2621503 8 -rw-r--r-- 1 root root 5432 Oct 6 14:31 /workspace/src/deny.toml
2621458 8 -rw-r--r-- 1 root root 7645 Oct 6 14:31 /workspace/src/HACKING.md
2621447 4 -rw-r--r-- 1 root root 1079 Oct 6 14:31 /workspace/src/LICENSE-MIT
2621463 8 -rw-r--r-- 1 root root 8023 Oct 6 14:31 /workspace/src/rad.1.adoc
2621469 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/build
2622155 4 -rw-r--r-- 1 root root 1083 Oct 6 14:31 /workspace/src/.gitsigners
2621459 4 -rw-r--r-- 1 root root 30 Oct 6 14:31 /workspace/src/.dockerignore
2621461 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/.cargo
2621442 4 drwxr-xr-x 2 root root 4096 Oct 6 14:32 /workspace/artifacts
2650384 4 drwxr-xr-x 6 root root 4096 Sep 22 23:09 /workspace/cache
2669079 4 -rw-r--r-- 1 root root 218 Sep 22 23:08 /workspace/cache/.rustdoc_fingerprint.json
2757416 4 drwxr-xr-x 24 root root 4096 Sep 24 10:45 /workspace/cache/doc
2650386 4 drwxr-xr-x 7 root root 4096 Sep 24 10:43 /workspace/cache/debug
2650385 4 drwxr-xr-x 2 root root 4096 Sep 22 23:02 /workspace/cache/tmp
2669080 4 -rw-r--r-- 1 root root 1036 Sep 24 12:56 /workspace/cache/.rustc_info.json
2752513 4 drwxr-xr-x 7 root root 4096 Sep 24 10:47 /workspace/cache/release
[2025-10-06T14:36:18Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:36:18Z DEBUG ambient_ci::plan] RUN: Action CargoFmt
[2025-10-06T14:36:18Z DEBUG ambient_ci::action] Plan::execute: plan=CargoFmt
[2025-10-06T14:36:18Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "fmt", "--check"]
cwd=/workspace/src (exists? true)
[2025-10-06T14:36:20Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:36:20Z DEBUG ambient_ci::plan] RUN: Action CargoClippy
[2025-10-06T14:36:20Z DEBUG ambient_ci::action] Plan::execute: plan=CargoClippy
[2025-10-06T14:36:20Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
cwd=/workspace/src (exists? true)
Checking erased-serde v0.4.6
Checking serde_fmt v1.0.3
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 portable-atomic v1.11.0
Checking unicode-width v0.2.1
Checking unit-prefix v0.5.1
Checking pin-project-lite v0.2.16
Checking tokio v1.47.1
Checking value-bag-serde1 v1.11.1
Checking serde-untagged v0.1.7
Checking value-bag v1.11.1
Checking console v0.16.0
Checking log v0.4.27
Compiling radicle-cli v0.16.0 (/workspace/src/crates/radicle-cli)
Checking prodash v29.0.2
Checking git2 v0.19.0
Checking mio v1.0.4
Checking gix-features v0.39.1
Checking gix-object v0.46.1
Checking gix-commitgraph v0.25.1
Checking gix-fs v0.12.1
Checking gix-tempfile v15.0.0
Checking gix-lock v15.0.1
Checking gix-url v0.28.2
Checking mio v0.8.11
Checking gix-revwalk v0.17.0
Checking gix-traverse v0.43.1
Checking gix-revision v0.31.1
Checking gix-diff v0.49.0
Checking gix-pack v0.56.0
Checking signal-hook-mio v0.2.4
Checking gix-refspec v0.27.0
Checking gix-negotiate v0.17.0
Checking radicle-git-ext v0.8.1
Checking gix-ref v0.49.1
Checking gix-credentials v0.26.0
Checking radicle-crypto v0.13.0 (/workspace/src/crates/radicle-crypto)
Checking gix-transport v0.44.0
Checking radicle-cob v0.16.0 (/workspace/src/crates/radicle-cob)
Checking gix-shallow v0.1.0
Checking crossterm v0.25.0
Checking gix-protocol v0.47.0
Checking gix-odb v0.66.0
Checking radicle v0.19.0 (/workspace/src/crates/radicle)
Checking inquire v0.7.5
Checking crossterm v0.29.0
Checking indicatif v0.18.0
Checking systemd-journal-logger v2.2.2
Checking os_info v3.12.0
Compiling radicle-node v0.15.0 (/workspace/src/crates/radicle-node)
Checking radicle-surf v0.22.0
Checking human-panic v2.0.3
Checking radicle-term v0.15.0 (/workspace/src/crates/radicle-term)
Checking radicle-systemd v0.10.0 (/workspace/src/crates/radicle-systemd)
Checking structured-logger v1.0.4
Checking escargot v0.5.10
Checking env_filter v0.1.3
Checking env_logger v0.11.8
Compiling radicle-remote-helper v0.13.0 (/workspace/src/crates/radicle-remote-helper)
Checking test-log v0.2.18
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 26.75s
[2025-10-06T14:36:47Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:36:47Z DEBUG ambient_ci::plan] RUN: Action CargoBuild
[2025-10-06T14:36:47Z DEBUG ambient_ci::action] Plan::execute: plan=CargoBuild
[2025-10-06T14:36:47Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "build", "--offline", "--locked", "--workspace", "--all-targets"]
cwd=/workspace/src (exists? true)
Compiling erased-serde v0.4.6
Compiling serde_fmt v1.0.3
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 unicode-width v0.2.1
Compiling portable-atomic v1.11.0
Compiling console v0.16.0
Compiling value-bag-serde1 v1.11.1
Compiling serde-untagged v0.1.7
Compiling value-bag v1.11.1
Compiling unit-prefix v0.5.1
Compiling indicatif v0.18.0
Compiling pin-project-lite v0.2.16
Compiling log v0.4.27
Compiling tokio v1.47.1
Compiling radicle-node v0.15.0 (/workspace/src/crates/radicle-node)
Compiling prodash v29.0.2
Compiling git2 v0.19.0
Compiling gix-features v0.39.1
Compiling mio v0.8.11
Compiling gix-object v0.46.1
Compiling gix-commitgraph v0.25.1
Compiling gix-revwalk v0.17.0
Compiling gix-fs v0.12.1
Compiling gix-tempfile v15.0.0
Compiling radicle-git-ext v0.8.1
Compiling gix-lock v15.0.1
Compiling gix-url v0.28.2
Compiling radicle-crypto v0.13.0 (/workspace/src/crates/radicle-crypto)
Compiling gix-traverse v0.43.1
Compiling gix-revision v0.31.1
Compiling gix-diff v0.49.0
Compiling radicle-cob v0.16.0 (/workspace/src/crates/radicle-cob)
Compiling mio v1.0.4
Compiling signal-hook-mio v0.2.4
Compiling gix-pack v0.56.0
Compiling gix-refspec v0.27.0
Compiling gix-credentials v0.26.0
Compiling radicle v0.19.0 (/workspace/src/crates/radicle)
Compiling gix-transport v0.44.0
Compiling gix-shallow v0.1.0
Compiling gix-ref v0.49.1
Compiling gix-negotiate v0.17.0
Compiling gix-odb v0.66.0
Compiling crossterm v0.25.0
Compiling gix-protocol v0.47.0
Compiling inquire v0.7.5
Compiling crossterm v0.29.0
Compiling systemd-journal-logger v2.2.2
Compiling os_info v3.12.0
Compiling radicle-cli v0.16.0 (/workspace/src/crates/radicle-cli)
Compiling radicle-surf v0.22.0
Compiling radicle-systemd v0.10.0 (/workspace/src/crates/radicle-systemd)
Compiling human-panic v2.0.3
Compiling radicle-term v0.15.0 (/workspace/src/crates/radicle-term)
Compiling structured-logger v1.0.4
Compiling escargot v0.5.10
Compiling env_filter v0.1.3
Compiling env_logger v0.11.8
Compiling radicle-remote-helper v0.13.0 (/workspace/src/crates/radicle-remote-helper)
Compiling test-log v0.2.18
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 1m 20s
[2025-10-06T14:38:09Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:38:09Z DEBUG ambient_ci::plan] RUN: Action CargoTest
[2025-10-06T14:38:09Z DEBUG ambient_ci::action] Plan::execute: plan=CargoTest
[2025-10-06T14:38:09Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
cwd=/workspace/src (exists? true)
Finished `test` profile [unoptimized + debuginfo] target(s) in 0.68s
Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle-e6d520bdf0d8e266)
running 238 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::tests::test_check_version ... ok
test cob::cache::tests::test_migrate_to ... ok
test cob::common::test::test_color ... ok
test cob::common::test::test_emojis ... ok
test cob::common::test::test_title ... ok
test cob::cache::migrations::_2::tests::test_patch_json_deserialization ... 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_update_rejected ... ok
test cob::identity::test::test_identity_updates ... 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::issue::test::test_invalid_actions ... ok
test cob::identity::test::test_identity_updates_concurrent_outdated ... ok
test cob::issue::test::test_invalid_tx ... ok
test cob::issue::test::test_invalid_tx_reference ... ok
test cob::issue::test::test_concurrency ... ok
test cob::issue::test::test_invalid_cob ... ok
test cob::issue::test::test_issue_comment ... 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_redact ... ok
test cob::issue::test::test_issue_create_and_get ... ok
test cob::issue::test::test_issue_create_and_unassign ... ok
test cob::issue::test::test_issue_create_and_change_state ... 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_edit_description ... 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_label ... 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_list ... ok
test cob::patch::cache::tests::test_list_by_status ... 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::test::test_patch_create_and_get ... ok
test cob::patch::cache::tests::test_counts ... ok
test cob::patch::test::test_patch_discussion ... ok
test cob::patch::test::test_patch_merge ... ok
test cob::patch::cache::tests::test_remove ... ok
test cob::patch::test::test_patch_redact ... ok
test cob::patch::test::test_patch_review ... ok
test cob::patch::test::test_patch_review_comment ... ok
test cob::patch::test::test_patch_review_duplicate ... ok
test cob::patch::test::test_patch_review_edit ... ok
test cob::patch::test::test_patch_review_edit_comment ... ok
test cob::patch::test::test_patch_review_remove_summary ... ok
test cob::patch::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::stream::tests::test_all_from ... ok
test cob::stream::tests::test_all_from_until ... ok
test cob::patch::test::test_patch_review_revision_redact ... ok
test cob::stream::tests::test_all_until ... ok
test cob::stream::tests::test_regression_from_until ... ok
test cob::stream::tests::test_from_until ... ok
test cob::thread::tests::test_comment_edit_missing ... ok
test cob::thread::tests::test_comment_edit_redacted ... ok
test cob::thread::tests::test_comment_redact_missing ... ok
test cob::thread::tests::test_duplicate_comments ... ok
test cob::thread::tests::test_edit_comment ... ok
test cob::thread::tests::test_redact_comment ... ok
test cob::thread::tests::test_timeline ... ok
test git::canonical::quorum::test::merge_base_commutative ... ok
test git::canonical::quorum::test::test_merge_bases ... ok
test cob::patch::test::test_patch_update ... 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 git::canonical::tests::test_quorum_properties ... 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 cob::thread::tests::prop_ordering ... 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 identity::doc::test::test_max_delegates ... 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_entries ... ok
test node::address::store::test::test_get_none ... ok
test node::address::store::test::test_insert_and_get ... ok
test node::address::store::test::test_insert_and_remove ... ok
test node::address::store::test::test_insert_and_update ... ok
test node::address::store::test::test_insert_duplicate ... ok
test node::address::store::test::test_node_aliases ... ok
test node::address::store::test::test_remove_nothing ... ok
test node::config::test::partial ... ok
test node::db::test::test_version ... ok
test node::features::test::test_operations ... ok
test node::notifications::store::test::test_branch_notifications ... ok
test node::notifications::store::test::test_clear ... ok
test node::notifications::store::test::test_cob_notifications ... ok
test node::notifications::store::test::test_counts_by_repo ... ok
test node::notifications::store::test::test_duplicate_notifications ... ok
test node::notifications::store::test::test_notification_status ... ok
test node::policy::store::test::test_follow_and_unfollow_node ... ok
test node::policy::store::test::test_node_aliases ... ok
test node::policy::store::test::test_node_policies ... ok
test node::policy::store::test::test_node_policy ... ok
test node::policy::store::test::test_repo_policies ... ok
test node::policy::store::test::test_repo_policy ... ok
test node::policy::store::test::test_seed_and_unseed_repo ... ok
test node::policy::store::test::test_update_alias ... ok
test node::policy::store::test::test_update_scope ... ok
test node::refs::store::test::test_count ... ok
test node::refs::store::test::test_set_and_delete ... ok
test node::refs::store::test::test_set_and_get ... ok
test node::address::store::test::test_empty ... ok
test node::routing::test::test_count ... ok
test node::routing::test::test_entries ... ok
test node::routing::test::test_insert_and_get_resources ... ok
test node::routing::test::test_insert_and_get ... ok
test node::routing::test::test_insert_duplicate ... ok
test node::routing::test::test_insert_existing_updated_time ... ok
test node::routing::test::test_len ... ok
test node::routing::test::test_insert_and_remove ... 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::sync::announce::test::announcer_adapts_target_to_reach ... ok
test node::routing::test::test_prune ... ok
test node::sync::announce::test::announcer_reached_max_replication_target ... ok
test node::sync::announce::test::announcer_preferred_seeds_or_replica_factor ... ok
test node::sync::announce::test::announcer_reached_min_replication_target ... ok
test node::sync::announce::test::announcer_synced_with_unknown_node ... ok
test node::sync::announce::test::announcer_reached_preferred_seeds ... 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::cannot_construct_announcer ... ok
test node::sync::announce::test::construct_only_preferred_seeds_provided ... ok
test node::sync::announce::test::construct_node_appears_in_multiple_input_sets ... ok
test node::sync::announce::test::invariant_progress_should_match_state ... ok
test node::sync::announce::test::local_node_in_preferred_seeds ... ok
test node::sync::announce::test::local_node_in_multiple_sets ... 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::preferred_seeds_already_synced ... 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::timed_out_after_reaching_success ... ok
test node::sync::announce::test::synced_with_same_node_multiple_times ... ok
test node::sync::fetch::test::all_nodes_are_candidates ... ok
test node::sync::fetch::test::could_not_reach_target ... ok
test node::sync::fetch::test::ignores_duplicates_and_local_node ... ok
test node::sync::fetch::test::preferred_seeds_target_returned_over_replicas ... ok
test node::sync::fetch::test::reaches_target_of_max_replicas ... ok
test node::sync::fetch::test::reaches_target_of_preferred_seeds ... ok
test node::sync::fetch::test::all_nodes_are_fetchable ... ok
test node::sync::test::ensure_replicas_construction ... ok
test node::sync::test::replicas_constrain_to ... ok
test node::sync::fetch::test::reaches_target_of_replicas ... ok
test node::test::test_command_result ... ok
test node::test::test_user_agent ... ok
test node::timestamp::tests::test_timestamp_max ... ok
test node::test::test_alias ... ok
test profile::test::canonicalize_home ... ok
test profile::test::test_config ... ok
test rad::tests::test_checkout ... ok
test rad::tests::test_fork ... ok
test rad::tests::test_init ... ok
test serde_ext::test::test_localtime ... ok
test serde_ext::test::test_localtime_ext ... ok
test storage::git::tests::test_references_of ... ok
test profile::config::test::schema ... ok
test storage::git::tests::test_remote_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_sign_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 storage::refs::tests::test_rid_verification ... ok
test identity::doc::test::prop_encode_decode ... ok
test cob::patch::cache::tests::test_find_by_revision ... ok
test result: ok. 238 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 12.07s
Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cli-4625dcbc8039538b)
running 14 tests
test commands::patch::review::builder::tests::test_review_comments_before ... ok
test commands::patch::review::builder::tests::test_review_comments_multiline ... ok
test commands::inspect::test::test_tree ... ok
test git::pretty_diff::test::test_pretty ... ignored
test commands::patch::review::builder::tests::test_review_comments_split_hunk ... ok
test git::ddiff::tests::diff_encode_decode_ddiff_hunk ... ok
test git::unified_diff::test::test_diff_content_encode_decode_content ... ok
test terminal::format::test::test_strip_comments ... ok
test terminal::format::test::test_bytes ... ok
test git::unified_diff::test::test_diff_encode_decode_diff ... ok
test terminal::patch::test::test_edit_display_message ... ok
test commands::patch::review::builder::tests::test_review_comments_basic ... ok
test terminal::patch::test::test_update_display_message ... ok
test terminal::patch::test::test_create_display_message ... ok
test result: ok. 13 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.04s
Running unittests src/main.rs (/workspace/cache/debug/deps/rad-1cb65e6810e14c53)
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-243072ca4c9e6c9e)
running 109 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 rad_block ... ok
test git_push_rollback ... ok
test git_tag ... ok
test rad_checkout ... ok
test git_push_converge ... ok
test rad_clone ... ok
test rad_clean ... ok
test rad_clone_bare ... ok
test rad_clone_all ... ok
test rad_clone_unknown ... ok
test rad_clone_connect ... ok
test rad_clone_directory ... 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_update_identity ... ok
test rad_cob_operations ... ok
test rad_cob_show ... ok
test rad_cob_update ... ok
test rad_diff ... ok
test rad_config ... ok
test rad_help ... ok
test rad_id_collaboration ... ignored, slow
test rad_id ... ok
test rad_id_conflict ... ok
test rad_id_private ... ok
test rad_fetch ... ok
test rad_fork ... ok
test rad_id_unknown_field ... ok
test rad_id_multi_delegate ... ok
test rad_id_update_delete_field ... ok
test rad_init ... ignored, part of many other tests
test rad_id_threshold_soft_fork ... ok
test rad_init_bare ... ok
test rad_id_threshold ... ok
test rad_init_detached_head ... ok
test rad_init_existing ... ok
test rad_init_no_git ... ok
test rad_init_existing_bare ... 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_private_seed ... ok
test rad_init_sync_not_connected ... ok
test rad_init_with_existing_remote ... ok
test rad_init_sync_preferred ... ok
test rad_inspect ... ok
test rad_issue ... ok
test rad_jj_bare ... ok
test rad_jj_colocated_patch ... ok
test rad_issue_list ... ok
test rad_key_mismatch ... ok
test rad_merge_no_ff ... ok
test rad_merge_after_update ... ok
test rad_merge_via_push ... ok
test rad_init_sync_and_clone ... ok
test rad_node ... ok
test rad_init_sync_timeout ... ok
test rad_node_connect ... 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_detached_head ... ok
test rad_patch_checkout_revision ... 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_fetch_1 ... FAILED
test rad_patch_merge_draft ... ok
test rad_patch_revert_merge ... ok
test rad_patch_update ... ok
test rad_patch_delete ... ok
test rad_patch_via_push ... FAILED
test rad_patch_open_explore ... ok
test rad_publish ... ok
test rad_review_by_hunk ... ok
test rad_seed_and_follow ... ok
test rad_seed_many ... ok
test rad_remote ... ok
test rad_push_and_pull_patches ... ok
test rad_self ... ok
test rad_patch_pull_update ... ok
test rad_unseed ... ok
test rad_sync_without_node ... ok
test rad_warn_old_nodes ... ok
test rad_unseed_many ... 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:
---- rad_patch_fetch_2 stdout ----
1759761599 [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-yLDJ3P/work/alice"][0m
1759761599 [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-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [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-yLDJ3P/work/alice"][0m
1759761600 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["checkout", "-b", "alice/1", "-q"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["commit", "--allow-empty", "-m", "Changes #1", "-q"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["push", "rad", "-o", "patch.message=Changes", "HEAD:refs/patches"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["checkout", "master", "-q"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["branch", "-D", "alice/1", "-q"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["update-ref", "-d", "refs/remotes/rad/alice/1"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["update-ref", "-d", "refs/remotes/rad/patches/5e2dedcc5d515fcbc1cca483d3376609fe889bfb"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761600 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["gc", "--prune=now"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["branch", "-r"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["pull"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-2.md: Running `git` with ["branch", "-r"] in `/tmp/radicle-yLDJ3P/work/alice`..[0m
thread 'rad_patch_fetch_2' panicked at crates/radicle-cli-test/src/lib.rs:503:36:
--- Expected
++++ actual: stdout
1 - rad/HEAD -> rad/master
2 1 | rad/master
3 2 | rad/patches/5e2dedcc5d515fcbc1cca483d3376609fe889bfb
Exit status: 0
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
---- rad_patch_fetch_1 stdout ----
[2m1759761599 rad_patch_fetch_1 storage: Setting ref: refs/rad/id -> 0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f[0m
[2m1759761599 rad_patch_fetch_1 storage: Setting ref: refs/heads/master -> f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354[0m
[2m1759761599 rad_patch_fetch_1 storage: Setting ref: HEAD -> refs/heads/master[0m
1759761599 [36mtest:[0m [36mInitialized project rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji for node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1759761599 rad_patch_fetch_1 node: Opening policy database..[0m
[2m1759761599 rad_patch_fetch_1 node: Default seeding policy set to 'block (all)'[0m
[2m1759761599 rad_patch_fetch_1 node: Initializing service (Test)..[0m
[2m1759761599 rad_patch_fetch_1 node: Opening node database..[0m
[2m1759761599 rad_patch_fetch_1 node: Address book is empty. Adding bootstrap nodes..[0m
[2m1759761599 rad_patch_fetch_1 node: 0 nodes added to address book[0m
[2m1759761599 rad_patch_fetch_1 service: Init @1759761599634[0m
[2m1759761599 rad_patch_fetch_1 service: Empty refs database, populating from storage..[0m
[2m1759761599 rad_patch_fetch_1 service: Saved local sync status for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji..[0m
[2m1759761599 rad_patch_fetch_1 service: Adding refs announcement for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to historical gossip messages..[0m
[2m1759761599 rad_patch_fetch_1 service: Not enough available peers to connect to (available=0, wanted=8)[0m
[2m1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> node: Listening on 0.0.0.0:38141..[0m
[2m1759761599 rad_patch_fetch_1 node: Binding control socket /tmp/radicle-G1LmQv/home/alice/.radicle/node/control.sock..[0m
[2m1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> node: Running node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi in /tmp/radicle-G1LmQv/home/alice/.radicle..[0m
[2m1759761599 rad_patch_fetch_1 node: Opening policy database..[0m
[2m1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Control thread listening on socket..[0m
[2m1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received command QueryState(..)[0m
[33m1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <signals> node: Signal notifications channel error: receiving on an empty and disconnected channel[0m
[2m1759761599 rad_patch_fetch_1 node: Default seeding policy set to 'block (all)'[0m
[2m1759761599 rad_patch_fetch_1 node: Initializing service (Test)..[0m
[2m1759761599 rad_patch_fetch_1 node: Opening node database..[0m
[2m1759761600 rad_patch_fetch_1 node: Address book is empty. Adding bootstrap nodes..[0m
[2m1759761600 rad_patch_fetch_1 node: 0 nodes added to address book[0m
[2m1759761600 rad_patch_fetch_1 service: Init @1759761599850[0m
[2m1759761600 rad_patch_fetch_1 service: Empty refs database, populating from storage..[0m
[2m1759761600 rad_patch_fetch_1 service: Not enough available peers to connect to (available=0, wanted=8)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> node: Listening on 0.0.0.0:37873..[0m
[2m1759761600 rad_patch_fetch_1 node: Binding control socket /tmp/radicle-G1LmQv/home/bob/.radicle/node/control.sock..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> node: Running node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk in /tmp/radicle-G1LmQv/home/bob/.radicle..[0m
[33m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <signals> node: Signal notifications channel error: receiving on an empty and disconnected channel[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Connect(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi, 0.0.0.0:38141, ConnectOptions { persistent: false, timeout: 30s })[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Connecting to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:38141)..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Attempted connection to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:38141)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Inbound connection from 127.0.0.1:54864 (fd=443)..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Registering outbound transport for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (fd=431)..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Outbound peer resource registered for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with id=2 (fd=431)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Accepted inbound connection from 127.0.0.1:54864 (fd=443)..[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Inbound peer resource registered with id=2 (fd=443)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Control thread listening on socket..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Session established with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (id=2) (fd=431) (outbound)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Connected to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:38141) (Outbound)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Subscribing to messages since timestamp 1759675200045..[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Session established with z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (id=2) (fd=443) (inbound)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Connected to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (127.0.0.1:54864) (Inbound)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Subscribing to messages since timestamp 1759675200045..[0m
1759761600 [36mtest:[0m [36mNode z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi has converged[0m
1759761600 [36mtest:[0m [36mNode has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))][0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received node announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 0 address(es) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1759761599634)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received node announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 address(es) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1759761599850)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1759761599634)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1759761599850)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Address store entry for node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi updated at 1759761599634[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1759761599636)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Address store entry for node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk updated at 1759761599850[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1759761599851)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1759761599851)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1759761599636)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received subscription filter from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
1759761600 [36mtest:[0m [36mNode z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk has converged[0m
1759761600 [36mtest:[0m [36mRunning `rad clone ["rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"]` in /tmp/radicle-G1LmQv/work/bob..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"status"}` on control socket[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"seed","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","scope":"all"}` on control socket[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Seed(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending subscription filter to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"seeds","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"}` on control socket[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Found 1 connected seed(s) and 0 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"session","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"}` on control socket[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"fetch","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi","timeout":{"secs":9,"nanos":0}}` on control socket[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Fetch(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [outbound connected] (all remotes)..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Opened new stream with id 12 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received `open` command for stream 12 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Worker processing incoming fetch for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk on stream 12..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> worker: z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk cloning from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 12..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched rad/id (22ms)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched data for 1 remote(s) (55ms)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched data refs for 1 remotes (67ms)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received `end-of-file` on stream 12 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <upload-pack> worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Upload pack finished (88ms)[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Upload process on stream 12 exited with result Ok(())[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received fetch result from worker for stream 12, remote z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk: Responder { rid: Some(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)), result: Ok(()) }[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Stream 12 of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk closing with 0 byte(s) sent and 0 byte(s) received[0m
[2m1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Received `close` command for stream 12 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Stream 12 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Validated 1 remote(s) (69ms)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Applied updates (71ms)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Finished clone of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (100ms)[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> cob: Loading object of type xyz.radicle.id at 0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: refs/rad/id -> 0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: refs/heads/master -> f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: HEAD -> refs/heads/master[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> cob: Loading object of type xyz.radicle.id at 0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f[0m
[2m1759761600 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(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs"), oid: Oid(a720994bd6bc3fd330cde0a92bc64aa1dcde901b) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f"), oid: Oid(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master"), oid: Oid(f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354) }, Skipped { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id"), oid: Oid(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root"), oid: Oid(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f) }], canonical: UpdatedCanonicalRefs { inner: {Qualified(RefString("refs/heads/master")): Oid(f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354)} }, namespaces: {PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi)}, clone: true, doc: DocAt { commit: Oid(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f), 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
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Found existing fetch request from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi, sending result..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sent fetch result for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi..[0m
[2m1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0dd5f68 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..a720994 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0dd5f68 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..f2de534 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0dd5f68 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Updating and announcing inventory for cloned repository rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji..[0m
1759761601 [36mtest:[0m [36mrad clone: ✓ Seeding policy updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with scope 'all'[0m
1759761601 [36mtest:[0m [36mrad clone: Fetching rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from the network, found 1 potential seed(s).[0m
1759761601 [36mtest:[0m [36mrad clone: ✓ Target met: 1 seed(s)[0m
1759761601 [36mtest:[0m [36mrad clone: ✓ Creating checkout in ./heartwood..[0m
1759761601 [36mtest:[0m [36mrad clone: ✓ Remote alice@z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi added[0m
1759761601 [36mtest:[0m [36mrad clone: ✓ Remote-tracking branch alice@z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/master created for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
1759761601 [36mtest:[0m [36mrad clone: ✓ Repository successfully cloned under /tmp/radicle-G1LmQv/work/bob/heartwood/[0m
1759761601 [36mtest:[0m [36mrad clone: ╭────────────────────────────────────╮[0m
1759761601 [36mtest:[0m [36mrad clone: │ heartwood │[0m
1759761601 [36mtest:[0m [36mrad clone: │ Radicle Heartwood Protocol & Stack │[0m
1759761601 [36mtest:[0m [36mrad clone: │ 0 issues · 0 patches │[0m
1759761601 [36mtest:[0m [36mrad clone: ╰────────────────────────────────────╯[0m
1759761601 [36mtest:[0m [36mrad clone: Run `cd ./heartwood` to go to the repository directory.[0m
1759761601 [36mtest:[0m [36mRan command `rad clone` (status=0)[0m
1759761601 [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-G1LmQv"][0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-1.md: Running `git` with ["rev-parse", "master"] in `/tmp/radicle-G1LmQv/work/alice`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-1.md: Running `git` with ["checkout", "-b", "alice/1", "-q"] in `/tmp/radicle-G1LmQv/work/alice`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-1.md: Running `git` with ["commit", "--allow-empty", "-m", "Change #1", "-q"] in `/tmp/radicle-G1LmQv/work/alice`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-1.md: Running `git` with ["rev-parse", "HEAD"] in `/tmp/radicle-G1LmQv/work/alice`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-1.md: Running `git` with ["push", "rad", "HEAD:alice/1"] in `/tmp/radicle-G1LmQv/work/alice`..[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"status"}` on control socket[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"seeds","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"}` on control socket[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Found 0 connected seed(s) and 1 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-1.md: Running `git` with ["status"] in `/tmp/radicle-G1LmQv/work/bob/heartwood`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-1.md: Running `git` with ["fetch", "--all"] in `/tmp/radicle-G1LmQv/work/bob/heartwood`..[0m
1759761601 [36mtest:[0m [36mrad-patch-fetch-1.md: Running `cat` with [".git/FETCH_HEAD"] in `/tmp/radicle-G1LmQv/work/bob/heartwood`..[0m
thread 'rad_patch_fetch_1' panicked at crates/radicle-cli-test/src/lib.rs:503:36:
--- Expected
++++ actual: stdout
1 1 | f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 branch 'master' of rad://z42hL2jL4XNk6K8oHQaSWfMgCL7ji
2 - 7461703ce0fda972df450d071d1d3702057a6352 not-for-merge branch 'alice/1' of rad://z42hL2jL4XNk6K8oHQaSWfMgCL7ji/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
3 2 | f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 not-for-merge branch 'master' of rad://z42hL2jL4XNk6K8oHQaSWfMgCL7ji/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
Exit status: 0
1759761601 [36mtest:[0m [36mNode z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk shutting down..[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"shutdown"}` on control socket[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Shutdown requested..[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 1 item(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1759761600971)[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1759761600972)[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Disconnecting peer with id=2: connection reset[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Transport handover for disconnecting peer with id=2 (fd=443)[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Disconnected from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (connection reset)[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Dropping peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk..[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> pool: Worker pool shutting down..[0m
[2m1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> node: Node shutdown completed for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk[0m
1759761601 [36mtest:[0m [36mNode z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi shutting down..[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"shutdown"}` on control socket[0m
[2m1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Shutdown requested..[0m
[2m1759761602 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> pool: Worker pool shutting down..[0m
[2m1759761602 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> node: Node shutdown completed for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi[0m
---- rad_patch_via_push stdout ----
1759761606 [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-7RtXbr/work/alice"][0m
1759761606 [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-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [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-7RtXbr/work/alice"][0m
1759761606 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["checkout", "-b", "feature/1"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["commit", "-a", "-m", "Add things", "-q", "--allow-empty"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [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-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-patch-via-push.md: Running `/workspace/cache/debug/rad` with ["patch", "show", "6035d2f582afbe01ff23ea87528ae523d76875b6"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["branch", "-vv"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["status", "--short", "--branch"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["fetch"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["push"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
1759761606 [36mtest:[0m [36mrad-patch-via-push.md: Running `git` with ["show-ref"] in `/tmp/radicle-7RtXbr/work/alice`..[0m
thread 'rad_patch_via_push' panicked at crates/radicle-cli-test/src/lib.rs:503: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:
rad_patch_fetch_1
rad_patch_fetch_2
rad_patch_via_push
test result: FAILED. 104 passed; 3 failed; 2 ignored; 0 measured; 0 filtered out; finished in 120.97s
error: test failed, to rerun pass `-p radicle-cli --test commands`
[2025-10-06T14:40:24Z ERROR ambient_ci::plan] ERROR: Action failed: failed to execute cargo
[2025-10-06T14:40:24Z ERROR ambient_ci::plan] caused by: command failed: "cargo": exit code 101
ERROR: failed to execute cargo
caused by: command failed: "cargo": exit code 101
EXIT CODE: 1
[2025-10-06T14:40:28Z 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": "0e5a4af2f44866dcfd25904f0b650f8bfdef1624",
"author": {
"id": "did:key:z6MkkPvBfjP4bQmco5Dm7UGsX2ruDBieEHi8n9DVJWX5sTEz",
"alias": "lorenz"
},
"title": "radicle/git/canonical: Actually use qualified refs",
"state": {
"status": "open",
"conflicts": []
},
"before": "2149770a4b93ac731a025838e44e4c45edb3c3ff",
"after": "e1cf94521e039c9555f26a3c4e7389b857f40960",
"commits": [
"e1cf94521e039c9555f26a3c4e7389b857f40960"
],
"target": "2149770a4b93ac731a025838e44e4c45edb3c3ff",
"labels": [],
"assignees": [],
"revisions": [
{
"id": "0e5a4af2f44866dcfd25904f0b650f8bfdef1624",
"author": {
"id": "did:key:z6MkkPvBfjP4bQmco5Dm7UGsX2ruDBieEHi8n9DVJWX5sTEz",
"alias": "lorenz"
},
"description": "Some tests actually do not use qualified patterns. The removed lines\n*should* not compile because the `qualified_pattern!` proc macro\n*should* validate the string literals at compile time, but it contains\na bug and actually does not assert well-formedness of qualified\nrefspec patterns. Rectify.",
"base": "2149770a4b93ac731a025838e44e4c45edb3c3ff",
"oid": "e1cf94521e039c9555f26a3c4e7389b857f40960",
"timestamp": 1759761106
}
]
}
}[2025-10-06T14:31:58Z INFO ambient] ambient starts
[2025-10-06T14:31:58Z DEBUG ambient] load default configuration file /home/_rad/.config/ambient/config.yaml if it exists
[2025-10-06T14:31:58Z 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-10-06T14:31:58Z 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-10-06T14:31:58Z DEBUG ambient_ci::project] load project state from /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/meta.yaml
[2025-10-06T14:31:58Z DEBUG ambient_ci::run] latest commit: "2149770a4b93ac731a025838e44e4c45edb3c3ff"
[2025-10-06T14:31:58Z DEBUG ambient_ci::run] is a git repository
[2025-10-06T14:31:58Z DEBUG ambient_ci::run] git repository is clean
[2025-10-06T14:31:58Z DEBUG ambient_ci::run] current (HEAD) commit: e1cf94521e039c9555f26a3c4e7389b857f40960
[2025-10-06T14:31:58Z DEBUG ambient_ci::run] no dry run requested
[2025-10-06T14:31:58Z DEBUG ambient_ci::run] forced run requested
[2025-10-06T14:31:58Z DEBUG ambient_ci::run] run? true
[2025-10-06T14:31:58Z INFO ambient_ci::run] project rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5: running CI
[2025-10-06T14:31:58Z DEBUG ambient_ci::run] Executing pre-plan steps
[2025-10-06T14:31:58Z DEBUG ambient_ci::plan] RUN: Action CargoFetch {
sourcedir: "/tmp/.tmpxtvLy5/src",
}
[2025-10-06T14:31:58Z DEBUG ambient_ci::action] Plan::execute: plan=CargoFetch {
sourcedir: "/tmp/.tmpxtvLy5/src",
}
[2025-10-06T14:31:58Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/dependencies",
"PATH": "/root/.cargo/bin:/bin:/home/_rad/.radicle/bin:/home/_rad/.cargo/bin",
"CARGO_TARGET_DIR": "/srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/cache",
},
source_dir: "/tmp/.tmpxtvLy5/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/tmp/.tmpxtvLy5/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/tmp/.tmpxtvLy5/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/tmp/.tmpxtvLy5/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "fetch"]
cwd=/tmp/.tmpxtvLy5/src (exists? true)
[2025-10-06T14:31:59Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:31:59Z DEBUG ambient_ci::plan] All actions were performed successfully
[2025-10-06T14:31:59Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmp1Q6HK8/src.tar
[2025-10-06T14:31:59Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmp1Q6HK8/deps.tar
[2025-10-06T14:32:09Z DEBUG ambient_ci::run] create_executor_vdrive: executor="/usr/bin/ambient-execute-plan"
[2025-10-06T14:32:09Z 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-10-06T14:32:09Z DEBUG ambient_ci::run] executor bin /usr/bin/ambient-execute-plan
[2025-10-06T14:32:09Z DEBUG ambient_ci::run] copying /usr/bin/ambient-execute-plan to /srv/tmp/.tmp1Q6HK8/ambient-execute-plan/run-ci
[2025-10-06T14:32:10Z DEBUG ambient_ci::run] copy OK: true
[2025-10-06T14:32:10Z DEBUG ambient_ci::run] create virtual drive /srv/tmp/.tmp1Q6HK8/executor.tar
[2025-10-06T14:32:46Z DEBUG ambient_ci::project] removing run log file /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-10-06T14:32:46Z DEBUG ambient_ci::project] statedir is /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5, exists? true
[2025-10-06T14:32:46Z DEBUG ambient_ci::project] creating run log file /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-10-06T14:32:46Z DEBUG ambient_ci::project] created run log file /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log OK
[2025-10-06T14:32:46Z DEBUG ambient_ci::cloud_init] with_hostname called: "ambient"
[2025-10-06T14:32:46Z DEBUG ambient_ci::cloud_init] with_runcmd called: "echo xyzzy > /dev/ttyS1"
[2025-10-06T14:32:46Z 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-10-06T14:32:46Z DEBUG ambient_ci::cloud_init] with_runcmd called: "poweroff"
[2025-10-06T14:32:46Z 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-10-06T14:32:46Z DEBUG ambient_ci::qemu] run QEMU
[2025-10-06T14:32:46Z DEBUG ambient_ci::qemu] create copy-on-write image and UEFI vars file
[2025-10-06T14:32:46Z DEBUG ambient_ci::qemu_utils] qemu-img create /srv/tmp/.tmpvIfe7E/.tmpn9WjsX/vm.qcow2 backing on /home/_rad/ambient.qcow2
[2025-10-06T14:32:46Z DEBUG ambient_ci::qemu] create cloud-init ISO file
[2025-10-06T14:32:46Z 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-10-06T14:32:46Z DEBUG ambient_ci::cloud_init] write /tmp/.tmp7AUC2C/meta-data
[2025-10-06T14:32:46Z DEBUG ambient_ci::cloud_init] write /tmp/.tmp7AUC2C/user-data
[2025-10-06T14:32:46Z DEBUG ambient_ci::qemu] set console log file to /srv/tmp/.tmpvIfe7E/.tmpn9WjsX/console.log
[2025-10-06T14:32:46Z DEBUG ambient_ci::util] create file /srv/tmp/.tmpvIfe7E/.tmpn9WjsX/console.log
[2025-10-06T14:32:46Z DEBUG ambient_ci::qemu] set run file to /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-10-06T14:32:46Z DEBUG ambient_ci::util] create file /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/run.log
[2025-10-06T14:32:46Z 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/.tmpvIfe7E/.tmpn9WjsX/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/.tmpvIfe7E/.tmpn9WjsX/vars.fd",
"-drive",
"format=qcow2,if=virtio,file=/srv/tmp/.tmpvIfe7E/.tmpn9WjsX/vm.qcow2",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmp1Q6HK8/executor.tar,readonly=on",
"-cdrom",
"/srv/tmp/.tmpvIfe7E/.tmpn9WjsX/cloud_init.iso",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmp1Q6HK8/src.tar,readonly=on",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmp1Q6HK8/artifacts.tar",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmp1Q6HK8/cache.tar",
"-drive",
"format=raw,if=virtio,file=/srv/tmp/.tmp1Q6HK8/deps.tar,readonly=on",
"-nodefaults",
"-no-user-config",
],
}
[2025-10-06T14:40:26Z DEBUG ambient_ci::qemu] QEMU finished OK
[2025-10-06T14:40:26Z DEBUG ambient_ci::qemu] run log:
--------------------==========
[2025-10-06T14:32:57Z TRACE ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-06T14:32:57Z DEBUG ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-06T14:32:57Z INFO ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-06T14:32:57Z WARN ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 starts
[2025-10-06T14:32:57Z ERROR ambient_execute_plan] ambient-execute-plan version 0.8.0@735cb79 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
[2025-10-06T14:32:57Z DEBUG ambient_ci::plan] RUN: Action Mkdir {
pathname: "/workspace",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::action] Plan::execute: plan=Mkdir {
pathname: "/workspace",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:32:57Z DEBUG ambient_ci::plan] RUN: Action Mkdir {
pathname: "/workspace/artifacts",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::action] Plan::execute: plan=Mkdir {
pathname: "/workspace/artifacts",
}
[2025-10-06T14:32:57Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:32:58Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:32:58Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
archive: "/dev/vdc",
directory: "/workspace/src",
}
[2025-10-06T14:32:58Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
archive: "/dev/vdc",
directory: "/workspace/src",
}
[2025-10-06T14:32:58Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:32:58Z TRACE ambient_ci::vdrive] extracting /dev/vdc to /workspace/src
[2025-10-06T14:32:59Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-06T14:32:59Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:32:59Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
archive: "/dev/vdf",
directory: "/workspace/deps",
}
[2025-10-06T14:32:59Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
archive: "/dev/vdf",
directory: "/workspace/deps",
}
[2025-10-06T14:32:59Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:32:59Z TRACE ambient_ci::vdrive] extracting /dev/vdf to /workspace/deps
[2025-10-06T14:33:28Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-06T14:33:28Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:33:28Z DEBUG ambient_ci::plan] RUN: Action TarExtract {
archive: "/dev/vde",
directory: "/workspace/cache",
}
[2025-10-06T14:33:28Z DEBUG ambient_ci::action] Plan::execute: plan=TarExtract {
archive: "/dev/vde",
directory: "/workspace/cache",
}
[2025-10-06T14:33:28Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
[2025-10-06T14:33:28Z TRACE ambient_ci::vdrive] extracting /dev/vde to /workspace/cache
[2025-10-06T14:36:18Z TRACE ambient_ci::vdrive] extraction OK
[2025-10-06T14:36:18Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:36:18Z DEBUG ambient_ci::plan] RUN: Action Spawn {
argv: [
"find",
"/workspace",
"-maxdepth",
"2",
"-ls",
],
}
[2025-10-06T14:36:18Z DEBUG ambient_ci::action] Plan::execute: plan=Spawn {
argv: [
"find",
"/workspace",
"-maxdepth",
"2",
"-ls",
],
}
[2025-10-06T14:36:18Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["find", "/workspace", "-maxdepth", "2", "-ls"]
cwd=/workspace/src (exists? true)
2621441 4 drwxr-xr-x 6 root root 4096 Oct 6 14:33 /workspace
2622157 4 drwxr-xr-x 3 root root 4096 Oct 6 14:31 /workspace/deps
2622158 0 -rw-r--r-- 1 root root 0 Sep 22 16:46 /workspace/deps/.package-cache-mutate
2622161 4 drwxr-xr-x 5 root root 4096 Sep 22 16:46 /workspace/deps/registry
2622159 128 -rw-r--r-- 1 root root 131072 Oct 6 14:31 /workspace/deps/.global-cache
2622160 0 -rw-r--r-- 1 root root 0 Sep 22 16:46 /workspace/deps/.package-cache
2621443 4 drwxr-xr-x 12 root root 4096 Oct 6 14:31 /workspace/src
2621499 4 -rw-r--r-- 1 root root 2653 Oct 6 14:31 /workspace/src/Cargo.toml
2622153 12 -rw-r--r-- 1 root root 9636 Oct 6 14:31 /workspace/src/CONTRIBUTING.md
2621500 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/.config
2621496 4 -rw-r--r-- 1 root root 1623 Oct 6 14:31 /workspace/src/build.rs
2621502 4 -rw-r--r-- 1 root root 426 Oct 6 14:31 /workspace/src/radicle-node.1.adoc
2621504 4 drwxr-xr-x 8 root root 4096 Oct 6 14:31 /workspace/src/.git
2622150 8 -rw-r--r-- 1 root root 7144 Oct 6 14:31 /workspace/src/rad-id.1.adoc
2621460 4 -rw-r--r-- 1 root root 2163 Oct 6 14:31 /workspace/src/VERSIONING.md
2621495 4 -rw-r--r-- 1 root root 3133 Oct 6 14:31 /workspace/src/README.md
2621551 12 -rw-r--r-- 1 root root 10975 Oct 6 14:31 /workspace/src/rad-patch.1.adoc
2622154 4 -rw-r--r-- 1 root root 79 Oct 6 14:31 /workspace/src/ARCHITECTURE.md
2621559 4 drwxr-xr-x 17 root root 4096 Oct 6 14:31 /workspace/src/crates
2621448 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/scripts
2621444 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/.radicle
2621467 16 -rw-r--r-- 1 root root 14022 Oct 6 14:31 /workspace/src/CHANGELOG.md
2621482 4 drwxr-xr-x 3 root root 4096 Oct 6 14:31 /workspace/src/debian
2621465 4 -rw-r--r-- 1 root root 42 Oct 6 14:31 /workspace/src/.envrc
2622151 4 -rw-r--r-- 1 root root 1203 Oct 6 14:31 /workspace/src/DCO
2622146 4 drwxr-xr-x 3 root root 4096 Oct 6 14:31 /workspace/src/.github
2622156 124 -rw-r--r-- 1 root root 122985 Oct 6 14:31 /workspace/src/Cargo.lock
2621552 4 drwxr-xr-x 5 root root 4096 Oct 6 14:31 /workspace/src/systemd
2621550 12 -rw-r--r-- 1 root root 9723 Oct 6 14:31 /workspace/src/LICENSE-APACHE
2621498 4 -rw-r--r-- 1 root root 101 Oct 6 14:31 /workspace/src/.gitignore
2622152 8 -rw-r--r-- 1 root root 5102 Oct 6 14:31 /workspace/src/flake.lock
2621464 4 -rw-r--r-- 1 root root 77 Oct 6 14:31 /workspace/src/rust-toolchain.toml
2621497 12 -rw-r--r-- 1 root root 10819 Oct 6 14:31 /workspace/src/flake.nix
2621468 4 -rw-r--r-- 1 root root 571 Oct 6 14:31 /workspace/src/git-remote-rad.1.adoc
2621466 4 -rw-r--r-- 1 root root 61 Oct 6 14:31 /workspace/src/.env.seed
2621503 8 -rw-r--r-- 1 root root 5432 Oct 6 14:31 /workspace/src/deny.toml
2621458 8 -rw-r--r-- 1 root root 7645 Oct 6 14:31 /workspace/src/HACKING.md
2621447 4 -rw-r--r-- 1 root root 1079 Oct 6 14:31 /workspace/src/LICENSE-MIT
2621463 8 -rw-r--r-- 1 root root 8023 Oct 6 14:31 /workspace/src/rad.1.adoc
2621469 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/build
2622155 4 -rw-r--r-- 1 root root 1083 Oct 6 14:31 /workspace/src/.gitsigners
2621459 4 -rw-r--r-- 1 root root 30 Oct 6 14:31 /workspace/src/.dockerignore
2621461 4 drwxr-xr-x 2 root root 4096 Oct 6 14:31 /workspace/src/.cargo
2621442 4 drwxr-xr-x 2 root root 4096 Oct 6 14:32 /workspace/artifacts
2650384 4 drwxr-xr-x 6 root root 4096 Sep 22 23:09 /workspace/cache
2669079 4 -rw-r--r-- 1 root root 218 Sep 22 23:08 /workspace/cache/.rustdoc_fingerprint.json
2757416 4 drwxr-xr-x 24 root root 4096 Sep 24 10:45 /workspace/cache/doc
2650386 4 drwxr-xr-x 7 root root 4096 Sep 24 10:43 /workspace/cache/debug
2650385 4 drwxr-xr-x 2 root root 4096 Sep 22 23:02 /workspace/cache/tmp
2669080 4 -rw-r--r-- 1 root root 1036 Sep 24 12:56 /workspace/cache/.rustc_info.json
2752513 4 drwxr-xr-x 7 root root 4096 Sep 24 10:47 /workspace/cache/release
[2025-10-06T14:36:18Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:36:18Z DEBUG ambient_ci::plan] RUN: Action CargoFmt
[2025-10-06T14:36:18Z DEBUG ambient_ci::action] Plan::execute: plan=CargoFmt
[2025-10-06T14:36:18Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "fmt", "--check"]
cwd=/workspace/src (exists? true)
[2025-10-06T14:36:20Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:36:20Z DEBUG ambient_ci::plan] RUN: Action CargoClippy
[2025-10-06T14:36:20Z DEBUG ambient_ci::action] Plan::execute: plan=CargoClippy
[2025-10-06T14:36:20Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "clippy", "--offline", "--locked", "--workspace", "--all-targets", "--no-deps", "--", "--deny", "warnings"]
cwd=/workspace/src (exists? true)
Checking erased-serde v0.4.6
Checking serde_fmt v1.0.3
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 portable-atomic v1.11.0
Checking unicode-width v0.2.1
Checking unit-prefix v0.5.1
Checking pin-project-lite v0.2.16
Checking tokio v1.47.1
Checking value-bag-serde1 v1.11.1
Checking serde-untagged v0.1.7
Checking value-bag v1.11.1
Checking console v0.16.0
Checking log v0.4.27
Compiling radicle-cli v0.16.0 (/workspace/src/crates/radicle-cli)
Checking prodash v29.0.2
Checking git2 v0.19.0
Checking mio v1.0.4
Checking gix-features v0.39.1
Checking gix-object v0.46.1
Checking gix-commitgraph v0.25.1
Checking gix-fs v0.12.1
Checking gix-tempfile v15.0.0
Checking gix-lock v15.0.1
Checking gix-url v0.28.2
Checking mio v0.8.11
Checking gix-revwalk v0.17.0
Checking gix-traverse v0.43.1
Checking gix-revision v0.31.1
Checking gix-diff v0.49.0
Checking gix-pack v0.56.0
Checking signal-hook-mio v0.2.4
Checking gix-refspec v0.27.0
Checking gix-negotiate v0.17.0
Checking radicle-git-ext v0.8.1
Checking gix-ref v0.49.1
Checking gix-credentials v0.26.0
Checking radicle-crypto v0.13.0 (/workspace/src/crates/radicle-crypto)
Checking gix-transport v0.44.0
Checking radicle-cob v0.16.0 (/workspace/src/crates/radicle-cob)
Checking gix-shallow v0.1.0
Checking crossterm v0.25.0
Checking gix-protocol v0.47.0
Checking gix-odb v0.66.0
Checking radicle v0.19.0 (/workspace/src/crates/radicle)
Checking inquire v0.7.5
Checking crossterm v0.29.0
Checking indicatif v0.18.0
Checking systemd-journal-logger v2.2.2
Checking os_info v3.12.0
Compiling radicle-node v0.15.0 (/workspace/src/crates/radicle-node)
Checking radicle-surf v0.22.0
Checking human-panic v2.0.3
Checking radicle-term v0.15.0 (/workspace/src/crates/radicle-term)
Checking radicle-systemd v0.10.0 (/workspace/src/crates/radicle-systemd)
Checking structured-logger v1.0.4
Checking escargot v0.5.10
Checking env_filter v0.1.3
Checking env_logger v0.11.8
Compiling radicle-remote-helper v0.13.0 (/workspace/src/crates/radicle-remote-helper)
Checking test-log v0.2.18
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 26.75s
[2025-10-06T14:36:47Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:36:47Z DEBUG ambient_ci::plan] RUN: Action CargoBuild
[2025-10-06T14:36:47Z DEBUG ambient_ci::action] Plan::execute: plan=CargoBuild
[2025-10-06T14:36:47Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "build", "--offline", "--locked", "--workspace", "--all-targets"]
cwd=/workspace/src (exists? true)
Compiling erased-serde v0.4.6
Compiling serde_fmt v1.0.3
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 unicode-width v0.2.1
Compiling portable-atomic v1.11.0
Compiling console v0.16.0
Compiling value-bag-serde1 v1.11.1
Compiling serde-untagged v0.1.7
Compiling value-bag v1.11.1
Compiling unit-prefix v0.5.1
Compiling indicatif v0.18.0
Compiling pin-project-lite v0.2.16
Compiling log v0.4.27
Compiling tokio v1.47.1
Compiling radicle-node v0.15.0 (/workspace/src/crates/radicle-node)
Compiling prodash v29.0.2
Compiling git2 v0.19.0
Compiling gix-features v0.39.1
Compiling mio v0.8.11
Compiling gix-object v0.46.1
Compiling gix-commitgraph v0.25.1
Compiling gix-revwalk v0.17.0
Compiling gix-fs v0.12.1
Compiling gix-tempfile v15.0.0
Compiling radicle-git-ext v0.8.1
Compiling gix-lock v15.0.1
Compiling gix-url v0.28.2
Compiling radicle-crypto v0.13.0 (/workspace/src/crates/radicle-crypto)
Compiling gix-traverse v0.43.1
Compiling gix-revision v0.31.1
Compiling gix-diff v0.49.0
Compiling radicle-cob v0.16.0 (/workspace/src/crates/radicle-cob)
Compiling mio v1.0.4
Compiling signal-hook-mio v0.2.4
Compiling gix-pack v0.56.0
Compiling gix-refspec v0.27.0
Compiling gix-credentials v0.26.0
Compiling radicle v0.19.0 (/workspace/src/crates/radicle)
Compiling gix-transport v0.44.0
Compiling gix-shallow v0.1.0
Compiling gix-ref v0.49.1
Compiling gix-negotiate v0.17.0
Compiling gix-odb v0.66.0
Compiling crossterm v0.25.0
Compiling gix-protocol v0.47.0
Compiling inquire v0.7.5
Compiling crossterm v0.29.0
Compiling systemd-journal-logger v2.2.2
Compiling os_info v3.12.0
Compiling radicle-cli v0.16.0 (/workspace/src/crates/radicle-cli)
Compiling radicle-surf v0.22.0
Compiling radicle-systemd v0.10.0 (/workspace/src/crates/radicle-systemd)
Compiling human-panic v2.0.3
Compiling radicle-term v0.15.0 (/workspace/src/crates/radicle-term)
Compiling structured-logger v1.0.4
Compiling escargot v0.5.10
Compiling env_filter v0.1.3
Compiling env_logger v0.11.8
Compiling radicle-remote-helper v0.13.0 (/workspace/src/crates/radicle-remote-helper)
Compiling test-log v0.2.18
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 1m 20s
[2025-10-06T14:38:09Z DEBUG ambient_ci::plan] RUN: Action finished OK
[2025-10-06T14:38:09Z DEBUG ambient_ci::plan] RUN: Action CargoTest
[2025-10-06T14:38:09Z DEBUG ambient_ci::action] Plan::execute: plan=CargoTest
[2025-10-06T14:38:09Z DEBUG ambient_ci::action] Plan::execute: context=Context {
envs: {
"CARGO_HOME": "/workspace/deps",
"PATH": "/root/.cargo/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin",
"CARGO_TARGET_DIR": "/workspace/cache",
},
source_dir: "/workspace/src",
}
SPAWN: argv=["cargo", "--version"]
cwd=/workspace/src (exists? true)
cargo 1.88.0 (873a06493 2025-05-10)
SPAWN: argv=["cargo", "clippy", "--version"]
cwd=/workspace/src (exists? true)
clippy 0.1.88 (6b00bc3880 2025-06-23)
SPAWN: argv=["rustc", "--version"]
cwd=/workspace/src (exists? true)
rustc 1.88.0 (6b00bc388 2025-06-23)
SPAWN: argv=["cargo", "test", "--offline", "--locked", "--workspace"]
cwd=/workspace/src (exists? true)
Finished `test` profile [unoptimized + debuginfo] target(s) in 0.68s
Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle-e6d520bdf0d8e266)
running 238 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::tests::test_check_version ... ok
test cob::cache::tests::test_migrate_to ... ok
test cob::common::test::test_color ... ok
test cob::common::test::test_emojis ... ok
test cob::common::test::test_title ... ok
test cob::cache::migrations::_2::tests::test_patch_json_deserialization ... 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_update_rejected ... ok
test cob::identity::test::test_identity_updates ... 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::issue::test::test_invalid_actions ... ok
test cob::identity::test::test_identity_updates_concurrent_outdated ... ok
test cob::issue::test::test_invalid_tx ... ok
test cob::issue::test::test_invalid_tx_reference ... ok
test cob::issue::test::test_concurrency ... ok
test cob::issue::test::test_invalid_cob ... ok
test cob::issue::test::test_issue_comment ... 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_redact ... ok
test cob::issue::test::test_issue_create_and_get ... ok
test cob::issue::test::test_issue_create_and_unassign ... ok
test cob::issue::test::test_issue_create_and_change_state ... 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_edit_description ... 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_label ... 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_list ... ok
test cob::patch::cache::tests::test_list_by_status ... 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::test::test_patch_create_and_get ... ok
test cob::patch::cache::tests::test_counts ... ok
test cob::patch::test::test_patch_discussion ... ok
test cob::patch::test::test_patch_merge ... ok
test cob::patch::cache::tests::test_remove ... ok
test cob::patch::test::test_patch_redact ... ok
test cob::patch::test::test_patch_review ... ok
test cob::patch::test::test_patch_review_comment ... ok
test cob::patch::test::test_patch_review_duplicate ... ok
test cob::patch::test::test_patch_review_edit ... ok
test cob::patch::test::test_patch_review_edit_comment ... ok
test cob::patch::test::test_patch_review_remove_summary ... ok
test cob::patch::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::stream::tests::test_all_from ... ok
test cob::stream::tests::test_all_from_until ... ok
test cob::patch::test::test_patch_review_revision_redact ... ok
test cob::stream::tests::test_all_until ... ok
test cob::stream::tests::test_regression_from_until ... ok
test cob::stream::tests::test_from_until ... ok
test cob::thread::tests::test_comment_edit_missing ... ok
test cob::thread::tests::test_comment_edit_redacted ... ok
test cob::thread::tests::test_comment_redact_missing ... ok
test cob::thread::tests::test_duplicate_comments ... ok
test cob::thread::tests::test_edit_comment ... ok
test cob::thread::tests::test_redact_comment ... ok
test cob::thread::tests::test_timeline ... ok
test git::canonical::quorum::test::merge_base_commutative ... ok
test git::canonical::quorum::test::test_merge_bases ... ok
test cob::patch::test::test_patch_update ... 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 git::canonical::tests::test_quorum_properties ... 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 cob::thread::tests::prop_ordering ... 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 identity::doc::test::test_max_delegates ... 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_entries ... ok
test node::address::store::test::test_get_none ... ok
test node::address::store::test::test_insert_and_get ... ok
test node::address::store::test::test_insert_and_remove ... ok
test node::address::store::test::test_insert_and_update ... ok
test node::address::store::test::test_insert_duplicate ... ok
test node::address::store::test::test_node_aliases ... ok
test node::address::store::test::test_remove_nothing ... ok
test node::config::test::partial ... ok
test node::db::test::test_version ... ok
test node::features::test::test_operations ... ok
test node::notifications::store::test::test_branch_notifications ... ok
test node::notifications::store::test::test_clear ... ok
test node::notifications::store::test::test_cob_notifications ... ok
test node::notifications::store::test::test_counts_by_repo ... ok
test node::notifications::store::test::test_duplicate_notifications ... ok
test node::notifications::store::test::test_notification_status ... ok
test node::policy::store::test::test_follow_and_unfollow_node ... ok
test node::policy::store::test::test_node_aliases ... ok
test node::policy::store::test::test_node_policies ... ok
test node::policy::store::test::test_node_policy ... ok
test node::policy::store::test::test_repo_policies ... ok
test node::policy::store::test::test_repo_policy ... ok
test node::policy::store::test::test_seed_and_unseed_repo ... ok
test node::policy::store::test::test_update_alias ... ok
test node::policy::store::test::test_update_scope ... ok
test node::refs::store::test::test_count ... ok
test node::refs::store::test::test_set_and_delete ... ok
test node::refs::store::test::test_set_and_get ... ok
test node::address::store::test::test_empty ... ok
test node::routing::test::test_count ... ok
test node::routing::test::test_entries ... ok
test node::routing::test::test_insert_and_get_resources ... ok
test node::routing::test::test_insert_and_get ... ok
test node::routing::test::test_insert_duplicate ... ok
test node::routing::test::test_insert_existing_updated_time ... ok
test node::routing::test::test_len ... ok
test node::routing::test::test_insert_and_remove ... 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::sync::announce::test::announcer_adapts_target_to_reach ... ok
test node::routing::test::test_prune ... ok
test node::sync::announce::test::announcer_reached_max_replication_target ... ok
test node::sync::announce::test::announcer_preferred_seeds_or_replica_factor ... ok
test node::sync::announce::test::announcer_reached_min_replication_target ... ok
test node::sync::announce::test::announcer_synced_with_unknown_node ... ok
test node::sync::announce::test::announcer_reached_preferred_seeds ... 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::cannot_construct_announcer ... ok
test node::sync::announce::test::construct_only_preferred_seeds_provided ... ok
test node::sync::announce::test::construct_node_appears_in_multiple_input_sets ... ok
test node::sync::announce::test::invariant_progress_should_match_state ... ok
test node::sync::announce::test::local_node_in_preferred_seeds ... ok
test node::sync::announce::test::local_node_in_multiple_sets ... 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::preferred_seeds_already_synced ... 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::timed_out_after_reaching_success ... ok
test node::sync::announce::test::synced_with_same_node_multiple_times ... ok
test node::sync::fetch::test::all_nodes_are_candidates ... ok
test node::sync::fetch::test::could_not_reach_target ... ok
test node::sync::fetch::test::ignores_duplicates_and_local_node ... ok
test node::sync::fetch::test::preferred_seeds_target_returned_over_replicas ... ok
test node::sync::fetch::test::reaches_target_of_max_replicas ... ok
test node::sync::fetch::test::reaches_target_of_preferred_seeds ... ok
test node::sync::fetch::test::all_nodes_are_fetchable ... ok
test node::sync::test::ensure_replicas_construction ... ok
test node::sync::test::replicas_constrain_to ... ok
test node::sync::fetch::test::reaches_target_of_replicas ... ok
test node::test::test_command_result ... ok
test node::test::test_user_agent ... ok
test node::timestamp::tests::test_timestamp_max ... ok
test node::test::test_alias ... ok
test profile::test::canonicalize_home ... ok
test profile::test::test_config ... ok
test rad::tests::test_checkout ... ok
test rad::tests::test_fork ... ok
test rad::tests::test_init ... ok
test serde_ext::test::test_localtime ... ok
test serde_ext::test::test_localtime_ext ... ok
test storage::git::tests::test_references_of ... ok
test profile::config::test::schema ... ok
test storage::git::tests::test_remote_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_sign_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 storage::refs::tests::test_rid_verification ... ok
test identity::doc::test::prop_encode_decode ... ok
test cob::patch::cache::tests::test_find_by_revision ... ok
test result: ok. 238 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 12.07s
Running unittests src/lib.rs (/workspace/cache/debug/deps/radicle_cli-4625dcbc8039538b)
running 14 tests
test commands::patch::review::builder::tests::test_review_comments_before ... ok
test commands::patch::review::builder::tests::test_review_comments_multiline ... ok
test commands::inspect::test::test_tree ... ok
test git::pretty_diff::test::test_pretty ... ignored
test commands::patch::review::builder::tests::test_review_comments_split_hunk ... ok
test git::ddiff::tests::diff_encode_decode_ddiff_hunk ... ok
test git::unified_diff::test::test_diff_content_encode_decode_content ... ok
test terminal::format::test::test_strip_comments ... ok
test terminal::format::test::test_bytes ... ok
test git::unified_diff::test::test_diff_encode_decode_diff ... ok
test terminal::patch::test::test_edit_display_message ... ok
test commands::patch::review::builder::tests::test_review_comments_basic ... ok
test terminal::patch::test::test_update_display_message ... ok
test terminal::patch::test::test_create_display_message ... ok
test result: ok. 13 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.04s
Running unittests src/main.rs (/workspace/cache/debug/deps/rad-1cb65e6810e14c53)
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-243072ca4c9e6c9e)
running 109 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 rad_block ... ok
test git_push_rollback ... ok
test git_tag ... ok
test rad_checkout ... ok
test git_push_converge ... ok
test rad_clone ... ok
test rad_clean ... ok
test rad_clone_bare ... ok
test rad_clone_all ... ok
test rad_clone_unknown ... ok
test rad_clone_connect ... ok
test rad_clone_directory ... 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_update_identity ... ok
test rad_cob_operations ... ok
test rad_cob_show ... ok
test rad_cob_update ... ok
test rad_diff ... ok
test rad_config ... ok
test rad_help ... ok
test rad_id_collaboration ... ignored, slow
test rad_id ... ok
test rad_id_conflict ... ok
test rad_id_private ... ok
test rad_fetch ... ok
test rad_fork ... ok
test rad_id_unknown_field ... ok
test rad_id_multi_delegate ... ok
test rad_id_update_delete_field ... ok
test rad_init ... ignored, part of many other tests
test rad_id_threshold_soft_fork ... ok
test rad_init_bare ... ok
test rad_id_threshold ... ok
test rad_init_detached_head ... ok
test rad_init_existing ... ok
test rad_init_no_git ... ok
test rad_init_existing_bare ... 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_private_seed ... ok
test rad_init_sync_not_connected ... ok
test rad_init_with_existing_remote ... ok
test rad_init_sync_preferred ... ok
test rad_inspect ... ok
test rad_issue ... ok
test rad_jj_bare ... ok
test rad_jj_colocated_patch ... ok
test rad_issue_list ... ok
test rad_key_mismatch ... ok
test rad_merge_no_ff ... ok
test rad_merge_after_update ... ok
test rad_merge_via_push ... ok
test rad_init_sync_and_clone ... ok
test rad_node ... ok
test rad_init_sync_timeout ... ok
test rad_node_connect ... 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_detached_head ... ok
test rad_patch_checkout_revision ... 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_fetch_1 ... FAILED
test rad_patch_merge_draft ... ok
test rad_patch_revert_merge ... ok
test rad_patch_update ... ok
test rad_patch_delete ... ok
test rad_patch_via_push ... FAILED
test rad_patch_open_explore ... ok
test rad_publish ... ok
test rad_review_by_hunk ... ok
test rad_seed_and_follow ... ok
test rad_seed_many ... ok
test rad_remote ... ok
test rad_push_and_pull_patches ... ok
test rad_self ... ok
test rad_patch_pull_update ... ok
test rad_unseed ... ok
test rad_sync_without_node ... ok
test rad_warn_old_nodes ... ok
test rad_unseed_many ... 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:
---- rad_patch_fetch_2 stdout ----
1759761599 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-yLDJ3P/work/alice"]
1759761599 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-yLDJ3P/work/alice`..
1759761600 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 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-yLDJ3P/work/alice"]
1759761600 test: rad-patch-fetch-2.md: Running `git` with ["checkout", "-b", "alice/1", "-q"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-patch-fetch-2.md: Running `git` with ["commit", "--allow-empty", "-m", "Changes #1", "-q"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-patch-fetch-2.md: Running `git` with ["push", "rad", "-o", "patch.message=Changes", "HEAD:refs/patches"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-patch-fetch-2.md: Running `git` with ["checkout", "master", "-q"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-patch-fetch-2.md: Running `git` with ["branch", "-D", "alice/1", "-q"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-patch-fetch-2.md: Running `git` with ["update-ref", "-d", "refs/remotes/rad/alice/1"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-patch-fetch-2.md: Running `git` with ["update-ref", "-d", "refs/remotes/rad/patches/5e2dedcc5d515fcbc1cca483d3376609fe889bfb"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761600 test: rad-patch-fetch-2.md: Running `git` with ["gc", "--prune=now"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761601 test: rad-patch-fetch-2.md: Running `git` with ["branch", "-r"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761601 test: rad-patch-fetch-2.md: Running `git` with ["pull"] in `/tmp/radicle-yLDJ3P/work/alice`..
1759761601 test: rad-patch-fetch-2.md: Running `git` with ["branch", "-r"] in `/tmp/radicle-yLDJ3P/work/alice`..
thread 'rad_patch_fetch_2' panicked at crates/radicle-cli-test/src/lib.rs:503:36:
--- Expected
++++ actual: stdout
1 - rad/HEAD -> rad/master
2 1 | rad/master
3 2 | rad/patches/5e2dedcc5d515fcbc1cca483d3376609fe889bfb
Exit status: 0
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
---- rad_patch_fetch_1 stdout ----
1759761599 rad_patch_fetch_1 storage: Setting ref: refs/rad/id -> 0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f
1759761599 rad_patch_fetch_1 storage: Setting ref: refs/heads/master -> f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354
1759761599 rad_patch_fetch_1 storage: Setting ref: HEAD -> refs/heads/master
1759761599 test: Initialized project rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji for node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761599 rad_patch_fetch_1 node: Opening policy database..
1759761599 rad_patch_fetch_1 node: Default seeding policy set to 'block (all)'
1759761599 rad_patch_fetch_1 node: Initializing service (Test)..
1759761599 rad_patch_fetch_1 node: Opening node database..
1759761599 rad_patch_fetch_1 node: Address book is empty. Adding bootstrap nodes..
1759761599 rad_patch_fetch_1 node: 0 nodes added to address book
1759761599 rad_patch_fetch_1 service: Init @1759761599634
1759761599 rad_patch_fetch_1 service: Empty refs database, populating from storage..
1759761599 rad_patch_fetch_1 service: Saved local sync status for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji..
1759761599 rad_patch_fetch_1 service: Adding refs announcement for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to historical gossip messages..
1759761599 rad_patch_fetch_1 service: Not enough available peers to connect to (available=0, wanted=8)
1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> node: Listening on 0.0.0.0:38141..
1759761599 rad_patch_fetch_1 node: Binding control socket /tmp/radicle-G1LmQv/home/alice/.radicle/node/control.sock..
1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> node: Running node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi in /tmp/radicle-G1LmQv/home/alice/.radicle..
1759761599 rad_patch_fetch_1 node: Opening policy database..
1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Control thread listening on socket..
1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received command QueryState(..)
1759761599 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <signals> node: Signal notifications channel error: receiving on an empty and disconnected channel
1759761599 rad_patch_fetch_1 node: Default seeding policy set to 'block (all)'
1759761599 rad_patch_fetch_1 node: Initializing service (Test)..
1759761599 rad_patch_fetch_1 node: Opening node database..
1759761600 rad_patch_fetch_1 node: Address book is empty. Adding bootstrap nodes..
1759761600 rad_patch_fetch_1 node: 0 nodes added to address book
1759761600 rad_patch_fetch_1 service: Init @1759761599850
1759761600 rad_patch_fetch_1 service: Empty refs database, populating from storage..
1759761600 rad_patch_fetch_1 service: Not enough available peers to connect to (available=0, wanted=8)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> node: Listening on 0.0.0.0:37873..
1759761600 rad_patch_fetch_1 node: Binding control socket /tmp/radicle-G1LmQv/home/bob/.radicle/node/control.sock..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> node: Running node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk in /tmp/radicle-G1LmQv/home/bob/.radicle..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <signals> node: Signal notifications channel error: receiving on an empty and disconnected channel
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Connect(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi, 0.0.0.0:38141, ConnectOptions { persistent: false, timeout: 30s })
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Connecting to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:38141)..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Attempted connection to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:38141)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Inbound connection from 127.0.0.1:54864 (fd=443)..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Registering outbound transport for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (fd=431)..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Outbound peer resource registered for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with id=2 (fd=431)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Accepted inbound connection from 127.0.0.1:54864 (fd=443)..
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Inbound peer resource registered with id=2 (fd=443)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Control thread listening on socket..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Session established with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (id=2) (fd=431) (outbound)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Connected to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (0.0.0.0:38141) (Outbound)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Subscribing to messages since timestamp 1759675200045..
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Session established with z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (id=2) (fd=443) (inbound)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Connected to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (127.0.0.1:54864) (Inbound)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Subscribing to messages since timestamp 1759675200045..
1759761600 test: Node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi has converged
1759761600 test: Node has missing routes: [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi))]
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received node announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 0 address(es) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1759761599634)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received node announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 address(es) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1759761599850)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1759761599634)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1759761599850)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Address store entry for node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi updated at 1759761599634
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1759761599636)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Address store entry for node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk updated at 1759761599850
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1759761599851)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1759761599851)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1759761599636)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received subscription filter from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761600 test: Node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk has converged
1759761600 test: Running `rad clone ["rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"]` in /tmp/radicle-G1LmQv/work/bob..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"status"}` on control socket
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"seed","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","scope":"all"}` on control socket
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Seed(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending subscription filter to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"seeds","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"}` on control socket
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Found 1 connected seed(s) and 0 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"session","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"}` on control socket
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command QueryState(..)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"fetch","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi","timeout":{"secs":9,"nanos":0}}` on control socket
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Received command Fetch(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [outbound connected] (all remotes)..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Opened new stream with id 12 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received `open` command for stream 12 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Worker processing incoming fetch for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk on stream 12..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> worker: z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk cloning from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 12..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched rad/id (22ms)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched data for 1 remote(s) (55ms)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Fetched data refs for 1 remotes (67ms)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received `end-of-file` on stream 12 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <upload-pack> worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Upload pack finished (88ms)
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <worker#0> worker: Upload process on stream 12 exited with result Ok(())
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Received fetch result from worker for stream 12, remote z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk: Responder { rid: Some(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)), result: Ok(()) }
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Stream 12 of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk closing with 0 byte(s) sent and 0 byte(s) received
1759761600 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Received `close` command for stream 12 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> wire: Stream 12 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Validated 1 remote(s) (69ms)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Applied updates (71ms)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> fetch: Finished clone of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (100ms)
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> cob: Loading object of type xyz.radicle.id at 0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: refs/rad/id -> 0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: refs/heads/master -> f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> storage: Setting ref: HEAD -> refs/heads/master
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <worker#0> cob: Loading object of type xyz.radicle.id at 0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f
1759761600 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(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs"), oid: Oid(a720994bd6bc3fd330cde0a92bc64aa1dcde901b) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f"), oid: Oid(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master"), oid: Oid(f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354) }, Skipped { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id"), oid: Oid(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f) }, Created { name: RefString("refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root"), oid: Oid(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f) }], canonical: UpdatedCanonicalRefs { inner: {Qualified(RefString("refs/heads/master")): Oid(f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354)} }, namespaces: {PublicKey(z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi)}, clone: true, doc: DocAt { commit: Oid(0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f), 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 } } }) }
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Found existing fetch request from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi, sending result..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sent fetch result for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi..
1759761600 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0dd5f68 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..a720994 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0dd5f68 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0dd5f68ca71afe6827b6a7a954ff4bddccd0d31f for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..f2de534 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Ref updated: * 0000000..0dd5f68 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Updating and announcing inventory for cloned repository rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji..
1759761601 test: rad clone: ✓ Seeding policy updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with scope 'all'
1759761601 test: rad clone: Fetching rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from the network, found 1 potential seed(s).
1759761601 test: rad clone: ✓ Target met: 1 seed(s)
1759761601 test: rad clone: ✓ Creating checkout in ./heartwood..
1759761601 test: rad clone: ✓ Remote alice@z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi added
1759761601 test: rad clone: ✓ Remote-tracking branch alice@z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/master created for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
1759761601 test: rad clone: ✓ Repository successfully cloned under /tmp/radicle-G1LmQv/work/bob/heartwood/
1759761601 test: rad clone: ╭────────────────────────────────────╮
1759761601 test: rad clone: │ heartwood │
1759761601 test: rad clone: │ Radicle Heartwood Protocol & Stack │
1759761601 test: rad clone: │ 0 issues · 0 patches │
1759761601 test: rad clone: ╰────────────────────────────────────╯
1759761601 test: rad clone: Run `cd ./heartwood` to go to the repository directory.
1759761601 test: Ran command `rad clone` (status=0)
1759761601 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-G1LmQv"]
1759761601 test: rad-patch-fetch-1.md: Running `git` with ["rev-parse", "master"] in `/tmp/radicle-G1LmQv/work/alice`..
1759761601 test: rad-patch-fetch-1.md: Running `git` with ["checkout", "-b", "alice/1", "-q"] in `/tmp/radicle-G1LmQv/work/alice`..
1759761601 test: rad-patch-fetch-1.md: Running `git` with ["commit", "--allow-empty", "-m", "Change #1", "-q"] in `/tmp/radicle-G1LmQv/work/alice`..
1759761601 test: rad-patch-fetch-1.md: Running `git` with ["rev-parse", "HEAD"] in `/tmp/radicle-G1LmQv/work/alice`..
1759761601 test: rad-patch-fetch-1.md: Running `git` with ["push", "rad", "HEAD:alice/1"] in `/tmp/radicle-G1LmQv/work/alice`..
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"status"}` on control socket
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"seeds","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji"}` on control socket
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji)
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Found 0 connected seed(s) and 1 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji
1759761601 test: rad-patch-fetch-1.md: Running `git` with ["status"] in `/tmp/radicle-G1LmQv/work/bob/heartwood`..
1759761601 test: rad-patch-fetch-1.md: Running `git` with ["fetch", "--all"] in `/tmp/radicle-G1LmQv/work/bob/heartwood`..
1759761601 test: rad-patch-fetch-1.md: Running `cat` with [".git/FETCH_HEAD"] in `/tmp/radicle-G1LmQv/work/bob/heartwood`..
thread 'rad_patch_fetch_1' panicked at crates/radicle-cli-test/src/lib.rs:503:36:
--- Expected
++++ actual: stdout
1 1 | f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 branch 'master' of rad://z42hL2jL4XNk6K8oHQaSWfMgCL7ji
2 - 7461703ce0fda972df450d071d1d3702057a6352 not-for-merge branch 'alice/1' of rad://z42hL2jL4XNk6K8oHQaSWfMgCL7ji/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
3 2 | f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354 not-for-merge branch 'master' of rad://z42hL2jL4XNk6K8oHQaSWfMgCL7ji/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
Exit status: 0
1759761601 test: Node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk shutting down..
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Received `{"command":"shutdown"}` on control socket
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <control> control: Shutdown requested..
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 1 item(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1759761600971)
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <service> service: Sending refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1759761600972)
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Disconnecting peer with id=2: connection reset
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> wire: Transport handover for disconnecting peer with id=2 (fd=443)
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Disconnected from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (connection reset)
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <service> service: Dropping peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk..
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> pool: Worker pool shutting down..
1759761601 z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk <runtime> node: Node shutdown completed for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk
1759761601 test: Node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi shutting down..
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Received `{"command":"shutdown"}` on control socket
1759761601 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <control> control: Shutdown requested..
1759761602 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> pool: Worker pool shutting down..
1759761602 z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi <runtime> node: Node shutdown completed for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi
---- rad_patch_via_push stdout ----
1759761606 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-7RtXbr/work/alice"]
1759761606 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-7RtXbr/work/alice`..
1759761606 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["init"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["ls"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 test: rad-init.md: Running `/workspace/cache/debug/rad` with ["node", "inventory"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 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-7RtXbr/work/alice"]
1759761606 test: rad-patch-via-push.md: Running `git` with ["checkout", "-b", "feature/1"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 test: rad-patch-via-push.md: Running `git` with ["commit", "-a", "-m", "Add things", "-q", "--allow-empty"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 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-7RtXbr/work/alice`..
1759761606 test: rad-patch-via-push.md: Running `/workspace/cache/debug/rad` with ["patch", "show", "6035d2f582afbe01ff23ea87528ae523d76875b6"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 test: rad-patch-via-push.md: Running `git` with ["branch", "-vv"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 test: rad-patch-via-push.md: Running `git` with ["status", "--short", "--branch"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 test: rad-patch-via-push.md: Running `git` with ["fetch"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 test: rad-patch-via-push.md: Running `git` with ["push"] in `/tmp/radicle-7RtXbr/work/alice`..
1759761606 test: rad-patch-via-push.md: Running `git` with ["show-ref"] in `/tmp/radicle-7RtXbr/work/alice`..
thread 'rad_patch_via_push' panicked at crates/radicle-cli-test/src/lib.rs:503: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:
rad_patch_fetch_1
rad_patch_fetch_2
rad_patch_via_push
test result: FAILED. 104 passed; 3 failed; 2 ignored; 0 measured; 0 filtered out; finished in 120.97s
error: test failed, to rerun pass `-p radicle-cli --test commands`
[2025-10-06T14:40:24Z ERROR ambient_ci::plan] ERROR: Action failed: failed to execute cargo
[2025-10-06T14:40:24Z ERROR ambient_ci::plan] caused by: command failed: "cargo": exit code 101
ERROR: failed to execute cargo
caused by: command failed: "cargo": exit code 101
EXIT CODE: 1
====================
[2025-10-06T14:40:26Z DEBUG ambient_ci::qemu] QEMU: CI run under exit code 1
[2025-10-06T14:40:27Z DEBUG ambient_ci::run] CI run exit code from QEMU: 1
[2025-10-06T14:40:27Z DEBUG ambient_ci::project] write project state to /srv/ambient-state/rad:z3gqcJUoA1n9HaHKufZs5FCSGazv5/meta.yaml
ERROR: CI run failed inside QEMU
<empty log>