tribuchet: building on jamie structuredAttrs is enabled Using versionCheckHook Running phase: unpackPhase unpacking source archive /nix/store/8lmbr9d86v1cyr1ckxvkx83r56llzaw1-z3gqcJUoA1n9HaHKufZs5FCSGazv5 source root is z3gqcJUoA1n9HaHKufZs5FCSGazv5 Executing cargoSetupPostUnpackHook Finished cargoSetupPostUnpackHook Running phase: patchPhase Executing cargoSetupPostPatchHook Validating consistency between /build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/Cargo.lock and /build/radicle-node-1.10.3-vendor/Cargo.lock Finished cargoSetupPostPatchHook Running phase: updateAutotoolsGnuConfigScriptsPhase Running phase: configurePhase Running phase: buildPhase Executing cargoBuildHook cargoBuildHook flags: -j 24 --target x86_64-unknown-linux-gnu --offline --profile release --package=radicle-node --package=radicle-cli --package=radicle-remote-helper Compiling libc v0.2.183 Compiling proc-macro2 v1.0.106 Compiling quote v1.0.45 Compiling unicode-ident v1.0.24 Compiling serde_core v1.0.228 Compiling cfg-if v1.0.4 Compiling serde v1.0.228 Compiling version_check v0.9.5 Compiling typenum v1.20.1 Compiling find-msvc-tools v0.1.9 Compiling subtle v2.6.1 Compiling shlex v1.3.0 Compiling memchr v2.8.0 Compiling const-oid v0.9.6 Compiling cpufeatures v0.2.17 Compiling regex-syntax v0.8.10 Compiling smallvec v1.15.1 Compiling thiserror v2.0.18 Compiling parking_lot_core v0.9.12 Compiling stable_deref_trait v1.2.1 Compiling scopeguard v1.2.0 Compiling fastrand v2.3.0 Compiling gix-trace v0.1.21 Compiling tinyvec_macros v0.1.1 Compiling bitflags v2.11.0 Compiling byteorder v1.5.0 Compiling crc32fast v1.5.0 Compiling tinyvec v1.11.0 Compiling same-file v1.0.6 Compiling lock_api v0.4.14 Compiling zlib-rs v0.6.5 Compiling heapless v0.8.0 Compiling typeid v1.0.3 Compiling walkdir v2.5.0 Compiling tree-sitter-language v0.1.7 Compiling generic-array v0.14.7 Compiling itoa v1.0.17 Compiling hash32 v0.3.1 Compiling hashbrown v0.16.1 Compiling erased-serde v0.4.10 Compiling pkg-config v0.3.32 Compiling getrandom v0.4.3 Compiling rustix v1.1.4 Compiling linux-raw-sys v0.12.1 Compiling aho-corasick v1.1.4 Compiling unicode-normalization v0.1.25 Compiling autocfg v1.5.0 Compiling libm v0.2.16 Compiling opaque-debug v0.3.1 Compiling syn v1.0.109 Compiling zerocopy v0.8.42 Compiling base16ct v0.2.0 Compiling vcpkg v0.2.15 Compiling zmij v1.0.21 Compiling spin v0.9.9 Compiling equivalent v1.0.2 Compiling data-encoding v2.10.0 Compiling num-traits v0.2.19 Compiling serde_json v1.0.149 Compiling signature v1.6.4 Compiling gix-utils v0.3.2 Compiling lazy_static v1.5.0 Compiling semver v1.0.27 Compiling ref-cast v1.0.25 Compiling jiff v0.2.23 Compiling dyn-clone v1.0.20 Compiling const-str v0.4.3 Compiling rustc_version v0.4.1 Compiling faster-hex v0.10.0 Compiling ed25519 v1.5.3 Compiling syn v2.0.117 Compiling base-x v0.2.11 Compiling amplify_num v0.5.3 Compiling once_cell v1.21.4 Compiling ascii v1.1.0 Compiling curve25519-dalek v4.1.3 Compiling jobserver v0.1.34 Compiling base64ct v1.8.3 Compiling num-bigint-dig v0.8.6 Compiling getrandom v0.2.17 Compiling keccak v0.1.6 Compiling rand_core v0.6.4 Compiling cc v1.2.57 Compiling parking_lot v0.12.5 Compiling ec25519 v0.1.0 Compiling pem-rfc7468 v0.7.0 Compiling regex-automata v0.4.14 Compiling memmap2 v0.9.10 Compiling ff v0.13.1 Compiling base32 v0.4.0 Compiling prodash v31.0.0 Compiling group v0.13.0 Compiling nonempty v0.12.0 Compiling percent-encoding v2.3.2 Compiling errno v0.3.14 Compiling writeable v0.6.2 Compiling utf8parse v0.2.2 Compiling litemap v0.8.1 Compiling icu_properties_data v2.1.2 Compiling anstyle-query v1.1.5 Compiling icu_normalizer_data v2.1.1 Compiling radicle-dag v0.10.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-dag) Compiling is_terminal_polyfill v1.70.2 Compiling base64 v0.21.7 Compiling radicle v0.25.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle) Compiling colorchoice v1.0.5 Compiling amplify_syn v2.0.1 Compiling signal-hook v0.3.18 Compiling shell-words v1.1.1 Compiling unicode-segmentation v1.12.0 Compiling anstyle v1.0.14 Compiling object v0.37.3 Compiling convert_case v0.10.0 Compiling signal-hook-registry v1.4.8 Compiling sem_safe v0.2.1 Compiling gimli v0.32.3 Compiling serde_fmt v1.1.0 Compiling indexmap v2.13.0 Compiling bytesize v2.3.1 Compiling either v1.15.0 Compiling adler2 v2.0.1 Compiling dunce v1.0.5 Compiling libz-sys v1.1.25 Compiling libgit2-sys v0.18.4+1.9.3 Compiling sqlite3-src v0.7.0 Compiling tree-sitter v0.24.7 Compiling siphasher v1.0.2 Compiling rustversion v1.0.22 Compiling fast-glob v0.3.3 Compiling amplify_derive v4.0.1 Compiling miniz_oxide v0.8.9 Compiling value-bag-serde1 v1.12.0 Compiling serde-untagged v0.1.9 Compiling value-bag v1.12.0 Compiling num-integer v0.1.46 Compiling signals_receipts v0.2.5 Compiling anstyle-parse v1.0.0 Compiling log v0.4.29 Compiling num-iter v0.1.45 Compiling gix-sec v0.14.0 Compiling portable-atomic v1.13.1 Compiling iana-time-zone v0.1.65 Compiling thiserror v1.0.69 Compiling rustc-demangle v0.1.27 Compiling mio v1.1.1 Compiling litrs v1.0.0 Compiling unicode-width v0.2.2 Compiling synstructure v0.13.2 Compiling serde_derive_internals v0.29.1 Compiling bstr v1.12.1 Compiling tempfile v3.27.0 Compiling document-features v0.2.12 Compiling signal-hook-mio v0.2.5 Compiling regex v1.12.3 Compiling chrono v0.4.44 Compiling anstream v1.0.0 Compiling gix-validate v0.11.1 Compiling gix-error v0.2.3 Compiling ppv-lite86 v0.2.21 Compiling addr2line v0.25.1 Compiling gix-date v0.15.3 Compiling gix-chunk v0.7.1 Compiling rand_chacha v0.3.1 Compiling gix-quote v0.7.1 Compiling radicle-signals v0.12.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-signals) Compiling gix-actor v0.41.0 Compiling colored v2.2.0 Compiling rand v0.8.5 Compiling heck v0.5.0 Compiling utf8_iter v1.0.4 Compiling strsim v0.11.1 Compiling streaming-iterator v0.1.9 Compiling clap_lex v1.1.0 Compiling arc-swap v1.9.1 Compiling console v0.16.3 Compiling serde_derive v1.0.228 Compiling thiserror-impl v2.0.18 Compiling match-lookup v0.1.2 Compiling data-encoding-macro-internal v0.1.17 Compiling zerofrom-derive v0.1.6 Compiling yoke-derive v0.8.1 Compiling ref-cast-impl v1.0.25 Compiling schemars_derive v1.2.1 Compiling curve25519-dalek-derive v0.1.1 Compiling zerovec-derive v0.11.2 Compiling displaydoc v0.2.5 Compiling derive_more-impl v2.1.1 Compiling amplify v4.9.0 Compiling backtrace v0.3.76 Compiling thiserror-impl v1.0.69 Compiling clap_builder v4.6.0 Compiling maybe-async v0.2.10 Compiling data-encoding-macro v0.1.19 Compiling clap_derive v4.6.0 Compiling tree-sitter-rust v0.23.3 Compiling tree-sitter-python v0.23.6 Compiling base256emoji v1.0.2 Compiling tree-sitter-html v0.23.2 Compiling multibase v0.9.2 Compiling tree-sitter-go v0.23.4 Compiling tree-sitter-md v0.3.2 Compiling tree-sitter-toml-ng v0.6.0 Compiling tree-sitter-c v0.23.4 Compiling tree-sitter-json v0.24.8 Compiling tree-sitter-ruby v0.23.1 Compiling tree-sitter-typescript v0.23.2 Compiling derive_more v2.1.1 Compiling gix-path v0.12.0 Compiling gix-packetline v0.21.5 Compiling crossterm v0.29.0 Compiling gix-features v0.48.0 Compiling gix-command v0.9.0 Compiling gix-config-value v0.18.0 Compiling zerofrom v0.1.6 Compiling yoke v0.8.1 Compiling gix-fs v0.21.1 Compiling gix-url v0.36.0 Compiling gix-glob v0.26.0 Compiling zerovec v0.11.5 Compiling zerotrie v0.2.3 Compiling gix-prompt v0.15.0 Compiling gix-tempfile v23.0.0 Compiling tree-sitter-css v0.23.2 Compiling tree-sitter-bash v0.23.3 Compiling gix-transport v0.57.0 Compiling gix-lock v23.0.0 Compiling gix-credentials v0.38.0 Compiling toml_datetime v0.7.5+spec-1.1.0 Compiling serde_spanned v1.0.4 Compiling anstyle-parse v0.2.7 Compiling form_urlencoded v1.2.2 Compiling toml_writer v1.0.7+spec-1.1.0 Compiling tinystr v0.8.2 Compiling potential_utf v0.1.4 Compiling crossbeam-utils v0.8.21 Compiling bytes v1.11.1 Compiling anyhow v1.0.102 Compiling icu_collections v2.1.1 Compiling unit-prefix v0.5.2 Compiling icu_locale_core v2.1.1 Compiling indicatif v0.18.4 Compiling toml v0.9.12+spec-1.1.0 Compiling anstream v0.6.21 Compiling clap v4.6.0 Compiling inquire v0.9.4 Compiling icu_provider v2.1.1 Compiling unicode-display-width v0.3.0 Compiling uuid v1.22.0 Compiling icu_normalizer v2.1.1 Compiling zeroize v1.9.0 Compiling schemars v1.2.1 Compiling radicle-git-ref-format v0.2.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-git-ref-format) Compiling nonempty v0.9.0 Compiling icu_properties v2.1.2 Compiling der v0.7.10 Compiling secrecy v0.10.3 Compiling sysinfo v0.37.2 Compiling radicle-cli v0.23.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-cli) Compiling crypto-common v0.1.7 Compiling block-buffer v0.10.4 Compiling block-padding v0.3.3 Compiling universal-hash v0.5.1 Compiling digest v0.10.7 Compiling inout v0.1.4 Compiling crypto-bigint v0.5.5 Compiling aead v0.5.2 Compiling cipher v0.4.4 Compiling poly1305 v0.8.0 Compiling polyval v0.6.2 Compiling sha1 v0.10.6 Compiling sha2 v0.10.9 Compiling hmac v0.12.1 Compiling sha1-checked v0.10.0 Compiling spki v0.7.3 Compiling signature v2.2.0 Compiling sec1 v0.7.3 Compiling rfc6979 v0.4.0 Compiling chacha20 v0.9.1 Compiling pkcs8 v0.10.2 Compiling gix-hash v0.25.0 Compiling cyphergraphy v0.3.1 Compiling pbkdf2 v0.12.2 Compiling ctr v0.9.2 Compiling aes v0.8.4 Compiling ghash v0.5.1 Compiling pkcs1 v0.7.5 Compiling ed25519 v2.2.3 Compiling gix-hashtable v0.15.0 Compiling ssh-encoding v0.2.0 Compiling cbc v0.1.2 Compiling gix-object v0.60.0 Compiling blowfish v0.9.1 Compiling sha3 v0.10.8 Compiling chacha20poly1305 v0.10.1 Compiling bcrypt-pbkdf v0.10.0 Compiling rsa v0.9.10 Compiling noise-framework v0.4.1 Compiling aes-gcm v0.10.3 Compiling gix-commitgraph v0.37.0 Compiling elliptic-curve v0.13.8 Compiling ssh-cipher v0.2.0 Compiling cypheraddr v0.4.1 Compiling radicle-localtime v0.1.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-localtime) Compiling idna_adapter v1.2.1 Compiling radicle-log v0.2.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-log) Compiling primeorder v0.13.6 Compiling ecdsa v0.16.9 Compiling ed25519-dalek v2.2.0 Compiling idna v1.1.0 Compiling gix-shallow v0.12.0 Compiling gix-revwalk v0.31.0 Compiling gix-diff v0.63.0 Compiling socks5-client v0.4.3 Compiling p521 v0.13.3 Compiling p256 v0.13.2 Compiling p384 v0.13.1 Compiling gix-revision v0.45.0 Compiling gix-traverse v0.57.0 Compiling gix-negotiate v0.31.0 Compiling cyphernet v0.5.4 Compiling gix-ref v0.63.0 Compiling url v2.5.8 Compiling salsa20 v0.10.2 Compiling pin-project-lite v0.2.17 Compiling siphasher v0.3.11 Compiling scrypt v0.11.0 Compiling gix-refspec v0.41.0 Compiling tokio v1.50.0 Compiling gix-pack v0.70.0 Compiling bloomy v1.2.0 Compiling tree-sitter-highlight v0.24.7 Compiling ssh-key v0.6.7 Compiling human-panic v2.0.6 Compiling gix-protocol v0.61.0 Compiling clap_complete v4.6.0 Compiling systemd-journal-logger v2.2.2 Compiling itertools v0.14.0 Compiling radicle-node v0.21.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-node) Compiling timeago v0.4.2 Compiling rs-release v0.1.11 Compiling humantime v2.3.0 Compiling crossbeam-channel v0.5.15 Compiling radicle-systemd v0.13.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-systemd) Compiling socket2 v0.5.10 Compiling lexopt v0.3.2 Compiling radicle-remote-helper v0.18.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-remote-helper) Compiling gix-odb v0.80.0 Compiling ssh-agent-lib v0.6.0 Compiling structured-logger v1.0.5 Compiling git2 v0.21.0 Compiling radicle-oid v0.2.2 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-oid) Compiling radicle-git-metadata v0.2.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-git-metadata) Compiling radicle-term v0.19.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-term) Compiling radicle-surf v0.29.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-surf) Compiling sqlite3-sys v0.18.0 Compiling sqlite v0.37.0 Compiling radicle-crypto v0.19.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-crypto) Compiling radicle-core v0.4.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-core) Compiling radicle-cob v0.21.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-cob) Compiling radicle-fetch v0.21.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-fetch) Compiling radicle-protocol v0.9.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-protocol) Finished `release` profile [optimized] target(s) in 1m 44s Executing cargoInstallPostBuildHook Finished cargoInstallPostBuildHook Finished cargoBuildHook buildPhase completed in 1 minutes 46 seconds Running phase: checkPhase Executing cargoCheckHook cargoCheckHook flags: -j 24 --profile release --target x86_64-unknown-linux-gnu --offline --package=radicle-node --package=radicle-cli --package=radicle-remote-helper -- --skip=service::message::tests::test_node_announcement_validate --skip=tests::test_announcement_relay --skip=tests::commands::rad_remote --skip=tests::e2e::test_connection_crossing --skip=rad_clone_partial_fail --skip=commands::patch::rad_patch_merge_unauthorized_branch Compiling rand v0.8.5 Compiling radicle v0.25.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle) Compiling radicle-log v0.2.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-log) Compiling snapbox-macros v1.1.0 Compiling normalize-line-endings v0.3.0 Compiling similar v3.1.1 Compiling radicle-node v0.21.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-node) Compiling escargot v0.5.15 Compiling diff v0.1.13 Compiling yansi v1.0.1 Compiling env_filter v1.0.0 Compiling test-log-macros v0.2.19 Compiling env_logger v0.11.9 Compiling pretty_assertions v1.4.1 Compiling num-bigint-dig v0.8.6 Compiling qcheck v1.0.0 Compiling snapbox v1.2.2 Compiling radicle-oid v0.2.2 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-oid) Compiling radicle-surf v0.29.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-surf) Compiling test-log v0.2.19 Compiling rsa v0.9.10 Compiling radicle-cli-test v0.14.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-cli-test) Compiling ssh-key v0.6.7 Compiling ssh-agent-lib v0.6.0 Compiling radicle-crypto v0.19.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-crypto) Compiling radicle-cob v0.21.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-cob) Compiling radicle-core v0.4.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-core) Compiling radicle-fetch v0.21.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-fetch) Compiling radicle-cli v0.23.0 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-cli) Compiling radicle-protocol v0.9.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-protocol) Compiling radicle-remote-helper v0.18.1 (/build/z3gqcJUoA1n9HaHKufZs5FCSGazv5/crates/radicle-remote-helper) Finished `release` profile [optimized] target(s) in 1m 00s Running unittests src/lib.rs (target/x86_64-unknown-linux-gnu/release/deps/radicle_cli-5d238a131921a2ef) running 48 tests test commands::block::args::test::should_not_parse ... ok test commands::block::args::test::should_parse_rid ... ok test commands::block::args::test::should_parse_nid ... ok test commands::cob::args::test::should_allow_log_json_format ... ok test commands::clone::args::test::should_parse_rid_url ... ok test commands::clone::args::test::should_parse_rid_non_urn ... ok test commands::clone::args::test::should_parse_rid_urn ... ok test commands::cob::args::test::should_allow_log_pretty_format ... ok test commands::id::args::test::should_parse_into_payload ... ok test commands::cob::args::test::should_allow_update_json_format ... ok test commands::cob::args::test::should_allow_show_json_format ... ok test commands::cob::args::test::should_not_allow_show_pretty_format ... ok test commands::cob::args::test::should_not_allow_update_pretty_format ... ok test commands::init::args::test::should_not_parse_rid_url ... ok test commands::init::args::test::should_parse_rid_non_urn ... ok test commands::fork::args::test::should_not_parse_rid_url ... ok test commands::id::args::test::should_parse_multiple_payloads ... ok test commands::id::args::test::should_parse_single_payload ... ok test commands::id::args::test::should_not_clobber_payload_args ... ok test commands::id::args::test::should_not_parse_single_payloads ... ok test commands::id::args::test::should_not_parse_single_payload ... ok test commands::init::args::test::should_parse_rid_urn ... ok test commands::fork::args::test::should_parse_rid_non_urn ... ok test commands::id::args::test::should_not_parse_into_payload - should panic ... ok test commands::patch::review::builder::tests::test_review_comments_multiline ... ok test commands::patch::review::builder::tests::test_review_comments_before ... ok test commands::patch::review::builder::tests::test_review_comments_basic ... ok test commands::inspect::test::test_tree ... ok test commands::fork::args::test::should_parse_rid_urn ... ok test commands::patch::review::builder::tests::test_review_comments_split_hunk ... ok test commands::publish::args::test::should_not_parse_rid_url ... ok test commands::publish::args::test::should_parse_rid_non_urn ... ok test commands::publish::args::test::should_parse_rid_urn ... ok test commands::watch::args::test::should_parse_ref_str ... ok test git::pretty_diff::test::test_pretty ... ignored test git::test::test_version_from_str ... ok test git::ddiff::tests::diff_encode_decode_ddiff_hunk ... ok test git::test::test_version_ord ... ok test git::unified_diff::test::test_diff_content_encode_decode_content ... ok test terminal::args::test::should_not_parse ... ok test terminal::args::test::should_parse_nid ... ok test terminal::args::test::should_parse_rid ... ok test terminal::format::test::test_bytes ... ok test terminal::format::test::test_strip_comments ... ok test git::unified_diff::test::test_diff_encode_decode_diff ... ok test terminal::patch::test::test_edit_display_message ... ok test terminal::patch::test::test_create_display_message ... ok test terminal::patch::test::test_update_display_message ... ok test result: ok. 47 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.02s Running unittests src/main.rs (target/x86_64-unknown-linux-gnu/release/deps/rad-1b850067bde0198b) 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 (target/x86_64-unknown-linux-gnu/release/deps/commands-e000c4b32af88c0d) running 124 tests test commands::cob::rad_cob_multiset ... ok test commands::clone::test_clone_without_seeds ... ok test commands::cob::test_cob_deletion ... ok test commands::cob::test_cob_replication ... ok test commands::checkout::rad_checkout has been running for over 60 seconds test commands::clone::rad_clone has been running for over 60 seconds test commands::clone::rad_clone_all has been running for over 60 seconds test commands::clone::rad_clone_bare has been running for over 60 seconds test commands::clone::rad_clone_connect has been running for over 60 seconds test commands::clone::rad_clone_directory has been running for over 60 seconds test commands::clone::rad_clone_scope has been running for over 60 seconds test commands::clone::rad_clone_unknown has been running for over 60 seconds test commands::cob::rad_cob_log has been running for over 60 seconds test commands::cob::rad_cob_migrate has been running for over 60 seconds test commands::cob::rad_cob_operations has been running for over 60 seconds test commands::cob::rad_cob_show has been running for over 60 seconds test commands::cob::rad_cob_update has been running for over 60 seconds test commands::cob::rad_cob_update_identity has been running for over 60 seconds test commands::git::git_push_amend has been running for over 60 seconds test commands::git::git_push_and_fetch has been running for over 60 seconds test commands::git::git_push_canonical has been running for over 60 seconds test commands::git::git_push_canonical_lightweight_tags has been running for over 60 seconds test commands::git::git_push_converge has been running for over 60 seconds test commands::git::git_push_diverge has been running for over 60 seconds test commands::git::git_push_force_with_lease has been running for over 60 seconds test commands::git::git_push_rollback has been running for over 60 seconds test commands::git::git_tag has been running for over 60 seconds test commands::id::rad_id has been running for over 60 seconds test commands::clone::rad_clone_unknown ... ok test commands::id::rad_id_collaboration ... ignored, slow test commands::cob::rad_cob_update_identity ... ok test commands::cob::rad_cob_migrate ... ok test commands::checkout::rad_checkout ... ok test commands::git::git_push_force_with_lease ... ok test commands::cob::rad_cob_show ... ok test commands::cob::rad_cob_operations ... ok test commands::cob::rad_cob_log ... ok test commands::clone::rad_clone_scope ... ok test commands::clone::rad_clone ... ok test commands::init::rad_init ... ignored, part of many other tests test commands::clone::rad_clone_directory ... ok test commands::clone::rad_clone_all ... ok test commands::clone::rad_clone_bare ... ok test commands::cob::rad_cob_update ... ok test commands::id::rad_id_update_delete_field ... ok test commands::init::rad_init_detached_head ... ok test commands::init::rad_init_bare ... ok test commands::id::rad_id_private ... ok test commands::init::rad_init_existing ... ok test commands::init::rad_init_existing_bare ... ok test commands::init::rad_init_no_git ... ok test commands::id::rad_id_unknown_field ... ok test commands::init::rad_init_private ... ok test commands::init::rad_init_no_seed ... ok test commands::init::rad_init_private_no_seed ... ok test commands::id::rad_id ... ok test commands::git::git_push_amend ... ok test commands::clone::rad_clone_connect ... ok test commands::git::git_push_diverge ... ok test commands::jj::rad_jj_bare ... ignored, the bare repository does not have a `rad` remote, and so it cannot determine the RID of the repository test commands::jj::rad_jj_colocated_patch ... ok test commands::git::git_push_and_fetch ... ok test commands::git::git_tag ... ok test commands::init::rad_init_sync_not_connected ... ok test commands::init::rad_init_with_existing_remote ... ok test commands::id::rad_id_unauthorized_delegate ... ok test commands::git::git_push_rollback ... ok test commands::init::rad_publish ... ok test commands::issue::rad_issue ... ok test commands::id::rad_id_threshold_soft_fork ... ok test commands::git::git_push_canonical_lightweight_tags ... ok test commands::issue::rad_issue_list ... ok test commands::id::rad_id_conflict ... ok test commands::patch::rad_merge_no_ff ... ok test commands::init::rad_init_private_clone ... ok test commands::patch::rad_patch ... ok test commands::inbox::rad_inbox ... ok test commands::patch::rad_patch_ahead_behind ... ok test commands::patch::rad_patch_change_base ... ok test commands::patch::rad_patch_checkout ... ok test commands::node::rad_node ... ok test commands::init::rad_init_private_clone_seed ... ok test commands::patch::rad_merge_after_update ... ok test commands::init::rad_init_private_seed ... ok test commands::patch::rad_patch_checkout_revision ... ok test commands::patch::rad_patch_detached_head ... ok test commands::patch::rad_merge_via_push ... ok test commands::git::git_push_canonical ... ok test commands::patch::rad_patch_draft ... ok test commands::init::rad_init_sync_and_clone ... ok test commands::id::rad_id_threshold ... FAILED test commands::init::rad_init_sync_preferred ... ok test commands::patch::rad_patch_merge_draft ... ok test commands::patch::rad_patch_diff ... ok test commands::patch::rad_patch_merge_into_canonical_ref_branch ... ok test commands::patch::rad_patch_edit ... ok test commands::patch::rad_patch_magic_push ... ok test commands::node::rad_node_connect_without_address ... ok test commands::patch::rad_patch_fetch_2 ... ok test commands::init::rad_init_sync_timeout ... ok test commands::patch::rad_patch_merge_wrong_branch ... ok test commands::node::rad_node_connect ... ok test commands::git::git_push_converge ... ok test commands::sigpipe::help ... ok test commands::patch::rad_patch_revert_merge ... ok test commands::patch::rad_patch_update ... ok test commands::policy::rad_seed_and_follow ... ok test commands::id::rad_id_multi_delegate ... ok test commands::policy::rad_block ... ok test commands::patch::rad_patch_revert_custom_branch ... ok test commands::utility::rad_auth ... ok test commands::utility::rad_auth_errors ... ok test commands::patch::rad_patch_review_no_options ... ok test commands::sigpipe::config ... ok test commands::policy::rad_seed_scope ... ok test commands::patch::rad_patch_via_push ... ok test commands::patch::rad_review_by_hunk ... ok test commands::sigpipe::rad_self ... ok test commands::policy::rad_seed_policy_allow_no_scope ... ok test commands::policy::rad_unseed ... ok test commands::utility::rad_help ... ok test commands::utility::rad_config ... ok test commands::policy::rad_unseed_many ... ok test commands::utility::rad_diff ... ok test commands::utility::rad_key_mismatch ... ok test commands::utility::rad_inspect ... ok test commands::utility::rad_self ... ok test commands::utility::framework_home ... ok test commands::utility::rad_warn_ipv6 ... ok test commands::utility::rad_warn_old_nodes ... ok test commands::patch::rad_patch_fetch_1 ... ok test commands::patch::rad_patch_checkout_force ... ok test commands::sync::rad_sync_without_node ... ok test commands::patch::rad_patch_open_explore ... ok test commands::patch::rad_patch_merge_on_first_push ... ok test commands::policy::rad_seed_many ... ok test commands::watch::rad_watch ... ok test commands::utility::rad_clean ... ok test commands::sync::rad_fetch ... ok test commands::remote::rad_remote ... ok test commands::utility::rad_fork ... ok test commands::patch::rad_push_and_pull_patches ... ok test commands::sync::rad_sync ... ok test commands::patch::rad_patch_pull_update ... ok test rad_remote ... ok test commands::sync::test_replication_via_seed ... ok test commands::patch::rad_patch_delete ... ok test commands::workflow::rad_workflow ... ok failures: ---- commands::id::rad_id_threshold stdout ---- 1792585579 test: rad-init:6: `rad init --name heartwood --description Radicle Heartwood Protocol & Stack --no-confirm --public -v` @ /build/.tmpbfUcXD/alice/work 1792585579 test: rad-init:28: `rad init` @ /build/.tmpbfUcXD/alice/work 1792585579 test: rad-init:35: `rad ls` @ /build/.tmpbfUcXD/alice/work 1792585579 test: rad-init:46: `rad node inventory` @ /build/.tmpbfUcXD/alice/work 1792585579 commands::id::rad_id_threshold node: Opening policy database.. 1792585580 commands::id::rad_id_threshold node: Default seeding policy set to 'block (all)' 1792585580 commands::id::rad_id_threshold node: Initializing service (Test).. 1792585581 commands::id::rad_id_threshold node: Opening node database.. 1792585581 commands::id::rad_id_threshold node: Address book is empty. Adding bootstrap nodes.. 1792585581 commands::id::rad_id_threshold node: 0 nodes added to address book 1792585581 commands::id::rad_id_threshold service: Init @1792585579831 1792585581 commands::id::rad_id_threshold service: Empty refs database, populating from storage.. 1792585581 commands::id::rad_id_threshold radicle_protocol::service: Migrating `rad/sigrefs` of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to force feature level parent. 1792585581 commands::id::rad_id_threshold service: Saved local sync status for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji.. 1792585581 commands::id::rad_id_threshold service: Adding refs announcement for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to historical gossip messages.. 1792585581 commands::id::rad_id_threshold service: Not enough available peers to connect to (available=0, wanted=8) 1792585581 commands::id::rad_id_threshold reactor-controller: Initializing reactor thread… 1792585581 service reactor: Entering reactor event loop 1792585581 service node: Listening on 127.0.0.1:33773.. 1792585583 commands::id::rad_id_threshold node: Binding control socket /build/.tmpbfUcXD/alice/.radicle/node/control.sock.. 1792585583 runtime node: Running node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi in /build/.tmpbfUcXD/alice/.radicle.. 1792585583 control control: Control thread listening on socket.. 1792585583 service service: Received command QueryState(..) 1792585583 signals node: Signal notifications channel error: receiving on a closed channel 1792585583 commands::id::rad_id_threshold node: Opening policy database.. 1792585583 commands::id::rad_id_threshold node: Default seeding policy set to 'block (all)' 1792585583 commands::id::rad_id_threshold node: Initializing service (Test).. 1792585583 commands::id::rad_id_threshold node: Opening node database.. 1792585583 commands::id::rad_id_threshold node: Address book is empty. Adding bootstrap nodes.. 1792585583 commands::id::rad_id_threshold node: 0 nodes added to address book 1792585583 commands::id::rad_id_threshold service: Init @1792585583065 1792585583 commands::id::rad_id_threshold service: Empty refs database, populating from storage.. 1792585583 commands::id::rad_id_threshold service: Not enough available peers to connect to (available=0, wanted=8) 1792585583 commands::id::rad_id_threshold reactor-controller: Initializing reactor thread… 1792585583 service reactor: Entering reactor event loop 1792585583 service node: Listening on 127.0.0.1:34665.. 1792585584 commands::id::rad_id_threshold node: Binding control socket /build/.tmpbfUcXD/seed/.radicle/node/control.sock.. 1792585584 runtime node: Running node z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z in /build/.tmpbfUcXD/seed/.radicle.. 1792585584 control control: Control thread listening on socket.. 1792585584 signals node: Signal notifications channel error: receiving on a closed channel 1792585584 service service: Received command QueryState(..) 1792585584 commands::id::rad_id_threshold node: Opening policy database.. 1792585584 commands::id::rad_id_threshold node: Default seeding policy set to 'block (all)' 1792585584 commands::id::rad_id_threshold node: Initializing service (Test).. 1792585584 commands::id::rad_id_threshold node: Opening node database.. 1792585585 commands::id::rad_id_threshold node: Address book is empty. Adding bootstrap nodes.. 1792585585 commands::id::rad_id_threshold node: 0 nodes added to address book 1792585585 commands::id::rad_id_threshold service: Init @1792585584407 1792585585 commands::id::rad_id_threshold service: Empty refs database, populating from storage.. 1792585585 commands::id::rad_id_threshold service: Not enough available peers to connect to (available=0, wanted=8) 1792585585 commands::id::rad_id_threshold reactor-controller: Initializing reactor thread… 1792585585 service reactor: Entering reactor event loop 1792585585 service node: Listening on 127.0.0.1:45067.. 1792585587 commands::id::rad_id_threshold node: Binding control socket /build/.tmpbfUcXD/bob/.radicle/node/control.sock.. 1792585587 runtime node: Running node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk in /build/.tmpbfUcXD/bob/.radicle.. 1792585587 control control: Control thread listening on socket.. 1792585587 signals node: Signal notifications channel error: receiving on a closed channel 1792585587 service service: Received command QueryState(..) 1792585587 service service: Received command Seed(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all) 1792585587 service reactor: Service was busy 118.930426ms which exceeds the timeout of 100ms 1792585587 service service: Received command Seed(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, followed) 1792585587 service reactor: Service was busy 142.362974ms which exceeds the timeout of 100ms 1792585587 service service: Not enough available peers to connect to (available=0, wanted=8) 1792585587 service reactor: Service was busy 127.152109ms which exceeds the timeout of 100ms 1792585587 service service: Received command Follow(z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z) 1792585587 service service: Received command QueryState(..) 1792585587 service service: Received command Connect(z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z, 127.0.0.1:34665, ConnectOptions { persistent: false, timeout: 30s }) 1792585587 service service: Connecting to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (127.0.0.1:34665).. 1792585587 service service: Attempted connection to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (127.0.0.1:34665) 1792585587 service wire: Registering outbound transport for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z.. 1792585587 service reactor: Registering transport 1792585587 service wire: Inbound connection from 127.0.0.1:60538.. 1792585587 service wire: Outbound peer resource registered for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585587 service wire: Accepted inbound connection from 127.0.0.1:60538.. 1792585587 service reactor: Registering transport 1792585587 service wire: Inbound peer resource registered 1792585587 service transport: Handshake with ProtocolArtifact { session: "", state: "" } is complete 1792585587 service wire: Session established with z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585587 service transport: Handshake with ProtocolArtifact { session: "", state: "" } is complete 1792585587 service wire: Session established with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585587 service service: Connected to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (127.0.0.1:34665) (Outbound) 1792585587 service service: Subscribing to messages since timestamp 1792499187959.. 1792585587 service service: Connected to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (127.0.0.1:60538) (Inbound) 1792585587 service service: Subscribing to messages since timestamp 1792499187959.. 1792585587 service service: Received node announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 0 address(es) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579831) 1792585587 service service: Received node announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 0 address(es) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585583065) 1792585587 service service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1792585579831) 1792585587 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 5.784 second(s) (t=1792585583065) 1792585588 service service: Address store entry for node z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z updated at 1792585583065 1792585588 service service: Received inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 0 item(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585583066) 1792585588 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 5.784 second(s) (t=1792585583066) 1792585588 service service: Received subscription filter from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585588 service service: Sending refs announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585579832) 1792585588 service service: Sending inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585579833) 1792585588 service reactor: Service was busy 226.073152ms which exceeds the timeout of 100ms 1792585588 service service: Received command QueryState(..) 1792585588 service service: Received command Connect(z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk, 127.0.0.1:45067, ConnectOptions { persistent: false, timeout: 30s }) 1792585588 service service: Connecting to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (127.0.0.1:45067).. 1792585588 service service: Attempted connection to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (127.0.0.1:45067) 1792585588 service wire: Registering outbound transport for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk.. 1792585588 service reactor: Registering transport 1792585588 service wire: Outbound peer resource registered for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585588 service wire: Inbound connection from 127.0.0.1:59664.. 1792585588 service wire: Accepted inbound connection from 127.0.0.1:59664.. 1792585588 service reactor: Registering transport 1792585588 service wire: Inbound peer resource registered 1792585588 service transport: Handshake with ProtocolArtifact { session: "", state: "" } is complete 1792585588 service transport: Handshake with ProtocolArtifact { session: "", state: "" } is complete 1792585588 service wire: Session established with z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585588 service wire: Session established with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585588 service service: Connected to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (127.0.0.1:45067) (Outbound) 1792585588 service service: Subscribing to messages since timestamp 1792499188254.. 1792585588 service service: Connected to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (127.0.0.1:59664) (Inbound) 1792585588 service service: Subscribing to messages since timestamp 1792499188254.. 1792585588 service service: Received node announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 0 address(es) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579831) 1792585588 service service: Received node announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 address(es) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585584407) 1792585588 service service: Address store entry for node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi updated at 1792585579831 1792585588 service service: Received inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579833) 1792585588 service service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1792585579831) 1792585588 service service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 5.462 second(s) (t=1792585584407) 1792585588 service service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1792585579833) 1792585588 service service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585588 service service: Missing seeded inventory rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji; initiating fetch.. 1792585588 service service: Starting fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585588 service service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [inbound connected] (all remotes).. 1792585588 service service: Received subscription filter from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585588 service reactor: Service was busy 389.574291ms which exceeds the timeout of 100ms 1792585588 service wire: Opened new stream with id 13 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585588 service service: Received refs announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579832) 1792585588 worker#0 worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585588 service service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1792585579832) 1792585588 service service: Queued fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585588 service service: Received inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579833) 1792585588 service service: Ignoring stale announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579833) 1792585588 worker#0 worker: z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z cloning from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585588 service service: Address store entry for node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk updated at 1792585584407 1792585588 service service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585584408) 1792585589 service service: Address store entry for node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi updated at 1792585579831 1792585589 service service: Received inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579833) 1792585589 service service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 0 millisecond(s) (t=1792585579833) 1792585589 service service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 5.462 second(s) (t=1792585584408) 1792585589 service service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585589 service service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585589 service service: Sending inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585579833) 1792585589 service service: Received subscription filter from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585589 service reactor: Service was busy 886.884571ms which exceeds the timeout of 100ms 1792585589 service reactor: Service was busy 886.73369ms which exceeds the timeout of 100ms 1792585589 service service: Received command QueryState(..) 1792585589 service wire: Received `open` command for stream 13 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585589 service service: Received command Connect(z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z, 127.0.0.1:34665, ConnectOptions { persistent: false, timeout: 30s }) 1792585589 service service: Connecting to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (127.0.0.1:34665).. 1792585589 worker#0 worker: Worker processing incoming fetch for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z on stream 13.. 1792585589 service service: Attempted connection to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (127.0.0.1:34665) 1792585589 worker#0 worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 13.. 1792585589 service wire: Registering outbound transport for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z.. 1792585589 service reactor: Registering transport 1792585589 service wire: Outbound peer resource registered for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585589 service service: Received inventory announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi with 1 item(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579833) 1792585589 service wire: Inbound connection from 127.0.0.1:48566.. 1792585589 service wire: Accepted inbound connection from 127.0.0.1:48566.. 1792585589 service reactor: Registering transport 1792585589 service wire: Inbound peer resource registered 1792585589 service service: Ignoring stale announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585579833) 1792585589 service transport: Handshake with ProtocolArtifact { session: "", state: "" } is complete 1792585589 service wire: Session established with z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585589 service transport: Handshake with ProtocolArtifact { session: "", state: "" } is complete 1792585589 service wire: Session established with z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585589 service service: Connected to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (127.0.0.1:34665) (Outbound) 1792585589 service service: Subscribing to messages since timestamp 1792499189200.. 1792585589 service service: Connected to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (127.0.0.1:48566) (Inbound) 1792585589 service service: Subscribing to messages since timestamp 1792499189201.. 1792585589 service service: Received node announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 address(es) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585584407) 1792585589 test: Waiting for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to route to [(RepoId(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji), PublicKey([230, 60, 201, 241, 191, 234, 99, 224, 69, 83, 141, 89, 171, 218, 190, 44, 26, 238, 5, 87, 218, 242, 137, 75, 243, 17, 93, 23, 125, 22, 80, 108]))] 1792585589 service service: Received node announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 0 address(es) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585583065) 1792585589 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 0 millisecond(s) (t=1792585583065) 1792585589 service service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1792585584407) 1792585589 service service: Address store entry for node z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z updated at 1792585583065 1792585589 service service: Received inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 0 item(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585583066) 1792585589 service service: Address store entry for node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk updated at 1792585584407 1792585589 service service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585584408) 1792585589 test: Found unexpected route for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi: (rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi) 1792585589 service service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 0 millisecond(s) (t=1792585584408) 1792585589 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 0 millisecond(s) (t=1792585583066) 1792585589 service service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585589 service service: Received subscription filter from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585589 service reactor: Service was busy 648.766839ms which exceeds the timeout of 100ms 1792585589 service reactor: Service was busy 648.572779ms which exceeds the timeout of 100ms 1792585589 service service: Not enough available peers to connect to (available=0, wanted=8) 1792585589 service service: Already fetching rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585589 service service: Sending inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 0 item(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585583066) 1792585589 service service: Sending inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 0 item(s) to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585583066) 1792585589 service service: Received inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 0 item(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585583066) 1792585589 service service: Received inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 0 item(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585583066) 1792585589 service service: Ignoring stale announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585583066) 1792585589 service service: Ignoring stale announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585583066) 1792585590 worker#0 radicle_fetch::state: Fetched rad/id (83ms) 1792585590 worker#0 radicle_fetch::state: Fetched data for 1 remote(s) (143ms) 1792585590 worker#0 radicle_fetch::state: Fetched data refs for 0 remotes (201ms) 1792585590 worker#0 radicle_fetch::state: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585590 worker#0 radicle_fetch::state: Validated 1 remote(s) (201ms) 1792585590 service wire: Received `end-of-file` on stream 13 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585590 upload-pack worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585590 worker#0 worker: Upload pack finished (980ms) 1792585590 worker#0 worker: Upload process on stream 13 exited with result Ok(()) 1792585590 service wire: Stream 13 of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z closing with 0 byte(s) sent and 0 byte(s) received 1792585590 service wire: Peer z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully 1792585590 service wire: Received `close` command for stream 13 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585590 service wire: Stream 13 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received 1792585590 worker#0 radicle_fetch::state: Applied updates (237ms) 1792585590 worker#0 radicle_fetch: Finished clone of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (1831ms) 1792585590 worker#0 storage: Obtained identity COB root commit '0656c217f917c3e06234771e9ecae53aba5e173e' via reference 'refs/rad/root' in namespace of founder 'did:key:z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi' of identity document 'rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji'. 1792585590 worker#0 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585590 worker#0 storage: Setting ref: refs/rad/id -> 0656c217f917c3e06234771e9ecae53aba5e173e 1792585590 worker#0 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585590 service service: Dequeued fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585590 service service: Skipping fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all refs are already in storage 1792585590 service service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully 1792585590 service service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585590 service service: Ref updated: * 0000000..55be805 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585590 service service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0656c217f917c3e06234771e9ecae53aba5e173e for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585590 service service: Ref updated: * 0000000..f2de534 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585590 service service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585590 service service: Updating and announcing inventory for cloned repository rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji.. 1792585590 service service: Sending inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 1 item(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585590395) 1792585590 service service: Sending inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 1 item(s) to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585590395) 1792585590 service service: Sending refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585590396) 1792585590 service service: Skipping refs announcement relay to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk [inbound connected]: peer isn't subscribed to rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585590 service reactor: Service was busy 440.083954ms which exceeds the timeout of 100ms 1792585590 service service: Received inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 1 item(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585590395) 1792585590 service service: Received inventory announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z with 1 item(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585590395) 1792585590 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 2.910 second(s) (t=1792585590395) 1792585590 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 0 millisecond(s) (t=1792585590395) 1792585590 service service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585590 service service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585590 service service: Received refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585590396) 1792585590 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 2.910 second(s) (t=1792585590396) 1792585590 service service: Refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji contains our own remote at 55be80540a7e9f10478fbc846913f2a6a19abe1d (t=1792585590396) 1792585590 service service: Updating sync status of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to 55be80540a7e9f10478fbc846913f2a6a19abe1d 1792585590 service service: Skipping fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all refs are already in storage 1792585591 service service: Not enough available peers to connect to (available=0, wanted=7) 1792585591 test: Found unexpected route for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi: (rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi) 1792585591 service service: Received command Fetch(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi) 1792585591 service service: Starting fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585591 service service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [inbound connected] (all remotes).. 1792585591 service wire: Opened new stream with id 21 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585591 worker#1 worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585591 service wire: Received `open` command for stream 21 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585591 worker#1 worker: Worker processing incoming fetch for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z on stream 21.. 1792585591 service service: Sending inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585584408) 1792585591 service service: Sending inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585584408) 1792585591 service reactor: Service was busy 137.744927ms which exceeds the timeout of 100ms 1792585591 service service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585584408) 1792585591 service service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 0 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585584408) 1792585591 worker#1 worker: z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z pulling from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585591 service service: Ignoring stale announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585584408) 1792585591 service service: Ignoring stale announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585584408) 1792585591 worker#1 worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 21.. 1792585591 worker#1 radicle_fetch::state: Fetched rad/id (64ms) 1792585591 worker#1 radicle_fetch::state: Fetched data for 1 remote(s) (159ms) 1792585591 worker#1 radicle_fetch::state: Fetched data refs for 0 remotes (191ms) 1792585591 worker#1 radicle_fetch::state: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585591 service wire: Received `end-of-file` on stream 21 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585591 upload-pack worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585591 worker#1 worker: Upload pack finished (300ms) 1792585591 worker#1 worker: Upload process on stream 21 exited with result Ok(()) 1792585591 worker#1 radicle_fetch::state: Validated 1 remote(s) (218ms) 1792585591 service wire: Stream 21 of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z closing with 0 byte(s) sent and 0 byte(s) received 1792585591 worker#1 radicle_fetch::state: Applied updates (218ms) 1792585591 service wire: Peer z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully 1792585591 worker#1 radicle_fetch: Finished pull of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji (392ms) 1792585591 service wire: Received `close` command for stream 21 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585591 service wire: Stream 21 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received 1792585591 worker#1 storage: Obtained identity COB root commit '0656c217f917c3e06234771e9ecae53aba5e173e' via reference 'refs/rad/root' in namespace of founder 'did:key:z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi' of identity document 'rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji'. 1792585591 worker#1 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585591 worker#1 storage: Setting ref: refs/rad/id -> 0656c217f917c3e06234771e9ecae53aba5e173e 1792585591 worker#1 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585591 service service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully 1792585591 test: rad-id-threshold:9: `rad id update --title Add Bob --description Add Bob as a delegate --delegate did:key:z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk --threshold 2` @ /build/.tmpbfUcXD/alice/work 1792585591 test: rad-id-threshold:20: `rad id update --title Add Bob --description Add Bob as a delegate --delegate did:key:z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk` @ /build/.tmpbfUcXD/alice/work 1792585592 test: rad-id-threshold:58: `touch REQUIREMENTS` @ /build/.tmpbfUcXD/alice/work 1792585592 test: rad-id-threshold:59: `git add REQUIREMENTS` @ /build/.tmpbfUcXD/alice/work 1792585592 service service: Nothing to announce, no refs were updated.. 1792585592 service reactor: Service was busy 428.106285ms which exceeds the timeout of 100ms 1792585592 test: rad-id-threshold:60: `git commit -v -m Define power requirements` @ /build/.tmpbfUcXD/alice/work 1792585592 test: rad-id-threshold:67: `git push rad master` @ /build/.tmpbfUcXD/alice/work 1792585592 test: rad-id-threshold:74: `rad sync -a` @ /build/.tmpbfUcXD/alice/work 1792585592 control control: Received `{"command":"status"}` on control socket 1792585592 control control: Received `{"command":"seedsFor","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","namespaces":["z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"]}` on control socket 1792585592 service service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji) 1792585592 service service: Found 1 connected seed(s) and 1 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585592 control control: Received `{"command":"subscribe"}` on control socket 1792585592 control control: Received `{"command":"announceRefsFor","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","namespaces":["z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"]}` on control socket 1792585592 service service: Received command AnnounceRefs(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji) 1792585592 service service: Announcing refs rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi @ 9f2bf77be1c2dc08d25e3b7b0bd7e0b37afbb848 to peers (t=1792585592568).. 1792585592 service service: Saved local sync status for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji.. 1792585593 service service: Skipping refs announcement relay to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk [outbound connected]: peer isn't subscribed to rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585593 service service: Sending refs announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585592568) 1792585593 service reactor: Service was busy 455.7826ms which exceeds the timeout of 100ms 1792585593 service service: Received refs announcement of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585592568) 1792585593 service service: Stored announcement from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi to be broadcast in 2.861 second(s) (t=1792585592568) 1792585593 service service: Starting fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585593 service service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [inbound connected] (1 remote(s)).. 1792585593 service wire: Opened new stream with id 29 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585593 worker#2 worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585593 service wire: Received `open` command for stream 29 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585593 worker#2 worker: Worker processing incoming fetch for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z on stream 29.. 1792585593 worker#2 worker: z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z pulling from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585593 worker#2 worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 29.. 1792585593 worker#2 radicle_fetch::state: Fetched rad/id (56ms) 1792585593 worker#2 radicle_fetch::state: Fetched data for 1 remote(s) (140ms) 1792585593 worker#2 radicle_fetch::state: Fetched data refs for 0 remotes (245ms) 1792585593 worker#2 radicle_fetch::state: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585593 service wire: Received `end-of-file` on stream 29 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585593 upload-pack worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585593 worker#2 radicle_fetch::state: Validated 1 remote(s) (268ms) 1792585593 worker#2 worker: Upload pack finished (412ms) 1792585593 worker#2 worker: Upload process on stream 29 exited with result Ok(()) 1792585593 service wire: Stream 29 of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z closing with 0 byte(s) sent and 0 byte(s) received 1792585593 service wire: Peer z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully 1792585593 service wire: Received `close` command for stream 29 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585593 service wire: Stream 29 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received 1792585593 worker#2 radicle_fetch::state: Applied updates (335ms) 1792585593 worker#2 radicle_fetch: Finished pull of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji (514ms) 1792585593 worker#2 storage: Obtained identity COB root commit '0656c217f917c3e06234771e9ecae53aba5e173e' via reference 'refs/rad/root' in namespace of founder 'did:key:z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi' of identity document 'rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji'. 1792585593 worker#2 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585593 worker#2 storage: Setting ref: refs/rad/id -> 7be665f9fccba97abb21b2fa85a6fd3181c72858 1792585593 worker#2 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585593 service service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully 1792585593 service service: Ref updated: ~ 55be805..9f2bf77 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585593 service service: Ref updated: ~ 0656c21..7be665f refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0656c217f917c3e06234771e9ecae53aba5e173e for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585593 service service: Ref updated: ~ f2de534..3e674d1 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585593 service service: Ref updated: ~ 0656c21..7be665f refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585593 service service: Sending refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585593624) 1792585593 service service: Skipping refs announcement relay to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk [inbound connected]: peer isn't subscribed to rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585593 service reactor: Service was busy 314.032997ms which exceeds the timeout of 100ms 1792585594 service reactor: Service was busy 260.313789ms which exceeds the timeout of 100ms 1792585594 service service: Received refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585593624) 1792585594 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 5.740 second(s) (t=1792585593624) 1792585594 service service: Refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji contains our own remote at 9f2bf77be1c2dc08d25e3b7b0bd7e0b37afbb848 (t=1792585593624) 1792585594 service service: Updating sync status of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to 9f2bf77be1c2dc08d25e3b7b0bd7e0b37afbb848 1792585594 service service: Skipping fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all refs are already in storage 1792585594 service reactor: Service was busy 325.387276ms which exceeds the timeout of 100ms 1792585594 test: rad-id-threshold:79: `git checkout -b add-readme` @ /build/.tmpbfUcXD/alice/work 1792585594 test: rad-id-threshold:80: `touch README.md` @ /build/.tmpbfUcXD/alice/work 1792585594 test: rad-id-threshold:81: `git add README.md` @ /build/.tmpbfUcXD/alice/work 1792585594 test: rad-id-threshold:82: `git commit -v -m Add README file` @ /build/.tmpbfUcXD/alice/work 1792585594 test: rad-id-threshold:89: `git push rad HEAD:refs/patches` @ /build/.tmpbfUcXD/alice/work 1792585595 test: rad-id-threshold:99: Directory /build/.tmpbfUcXD/seed/work does not exist. Creating.. 1792585595 test: rad-id-threshold:99: `rad sync rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji -f` @ /build/.tmpbfUcXD/seed/work 1792585595 control control: Received `{"command":"status"}` on control socket 1792585595 control control: Received `{"command":"seedsFor","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","namespaces":["z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z"]}` on control socket 1792585595 service service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji) 1792585595 service service: Found 1 connected seed(s) and 0 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585595 control control: Received `{"command":"session","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"}` on control socket 1792585595 service service: Received command QueryState(..) 1792585595 control control: Received `{"command":"fetch","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi","timeout":{"secs":9,"nanos":0},"signedReferencesMinimumFeatureLevel":null}` on control socket 1792585595 service service: Received command Fetch(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi) 1792585595 service service: Starting fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585595 service service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [inbound connected] (all remotes).. 1792585595 service wire: Opened new stream with id 37 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585595 worker#3 worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585595 service wire: Received `open` command for stream 37 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585595 worker#3 worker: Worker processing incoming fetch for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z on stream 37.. 1792585595 worker#3 worker: z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z pulling from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585595 worker#3 worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 37.. 1792585595 control control: Command returned error: i/o error: Broken pipe (os error 32) 1792585595 worker#3 radicle_fetch::state: Fetched rad/id (56ms) 1792585595 worker#3 radicle_fetch::state: Fetched data for 2 remote(s) (191ms) 1792585596 service reactor: Service was busy 182.986191ms which exceeds the timeout of 100ms 1792585596 worker#3 radicle_fetch::state: Fetched data refs for 0 remotes (435ms) 1792585596 worker#3 radicle_fetch::state: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585596 service wire: Received `end-of-file` on stream 37 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585596 upload-pack worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585596 worker#3 worker: Upload pack finished (530ms) 1792585596 worker#3 worker: Upload process on stream 37 exited with result Ok(()) 1792585596 service wire: Stream 37 of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z closing with 0 byte(s) sent and 0 byte(s) received 1792585596 service wire: Peer z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully 1792585596 worker#3 radicle_fetch::state: Pruning delegate z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk tips, missing 'rad/sigrefs' 1792585596 worker#3 radicle_fetch::state: Validated 1 remote(s) (516ms) 1792585596 service wire: Received `close` command for stream 37 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585596 service wire: Stream 37 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received 1792585596 worker#3 radicle_fetch::state: Applied updates (520ms) 1792585596 worker#3 radicle_fetch: Finished pull of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji (710ms) 1792585596 worker#3 worker: Validation error: missing `refs/namespaces/z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk/refs/rad/sigrefs` 1792585596 worker#3 storage: Obtained identity COB root commit '0656c217f917c3e06234771e9ecae53aba5e173e' via reference 'refs/rad/root' in namespace of founder 'did:key:z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi' of identity document 'rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji'. 1792585596 worker#3 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585596 worker#3 storage: Setting ref: refs/rad/id -> 7be665f9fccba97abb21b2fa85a6fd3181c72858 1792585596 worker#3 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585596 worker#3 cob: Loading object of type xyz.radicle.patch at b09b2aa0ee055671c811e9ad4ba73eed211ebaa3. 1792585596 service service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully 1792585596 service service: Ref updated: ~ 9f2bf77..e5c2007 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585596 service service: Ref updated: * 0000000..b09b2aa refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.patch/b09b2aa0ee055671c811e9ad4ba73eed211ebaa3 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585596 service service: Ref updated: * 0000000..964513c refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/patches/b09b2aa0ee055671c811e9ad4ba73eed211ebaa3 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585596 test: rad-id-threshold:111: `rad inspect --identity` @ /build/.tmpbfUcXD/alice/work 1792585597 test: rad-id-threshold:132: `rad inspect --refs` @ /build/.tmpbfUcXD/alice/work 1792585597 test: rad-id-threshold:154: `rad inspect --sigrefs` @ /build/.tmpbfUcXD/alice/work 1792585597 service service: Sending refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585596772) 1792585597 service service: Skipping refs announcement relay to z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk [inbound connected]: peer isn't subscribed to rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585597 service reactor: Service was busy 417.211086ms which exceeds the timeout of 100ms 1792585597 service service: Received refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585596772) 1792585597 service service: Stored announcement from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z to be broadcast in 2.559 second(s) (t=1792585596772) 1792585597 service service: Refs announcement of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji contains our own remote at e5c200775dc6da38306f62c995df67a1ab1bf62d (t=1792585596772) 1792585597 test: rad-id-threshold:161: `rad ls` @ /build/.tmpbfUcXD/alice/work 1792585597 service service: Updating sync status of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to e5c200775dc6da38306f62c995df67a1ab1bf62d 1792585597 service service: Skipping fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all refs are already in storage 1792585597 test: rad-id-threshold:174: Directory /build/.tmpbfUcXD/bob/work does not exist. Creating.. 1792585597 test: rad-id-threshold:174: `rad clone rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji` @ /build/.tmpbfUcXD/bob/work 1792585597 control control: Received `{"command":"status"}` on control socket 1792585597 control control: Received `{"command":"seed","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","scope":"followed"}` on control socket 1792585597 service service: Received command Seed(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, followed) 1792585597 service service: Sending subscription filter to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585597 service service: Sending subscription filter to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585597 service service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585597 service service: Received subscription filter from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585597 control control: Received `{"command":"seedsFor","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","namespaces":["z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk"]}` on control socket 1792585597 service service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji) 1792585597 service service: Found 2 connected seed(s) and 0 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585597 control control: Received `{"command":"session","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"}` on control socket 1792585597 service service: Received command QueryState(..) 1792585597 control control: Received `{"command":"fetch","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","nid":"z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi","timeout":{"secs":9,"nanos":0},"signedReferencesMinimumFeatureLevel":null}` on control socket 1792585597 service service: Received command Fetch(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi) 1792585597 service service: Starting fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585597 service service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi [inbound connected] (all remotes).. 1792585597 service wire: Opened new stream with id 13 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585597 service wire: Received `open` command for stream 13 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585597 worker#0 worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585597 worker#4 worker: Worker processing incoming fetch for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk on stream 13.. 1792585597 worker#0 worker: z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk cloning from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585597 worker#4 worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 13.. 1792585597 worker#0 radicle_fetch::state: Fetched rad/id (94ms) 1792585597 worker#0 radicle_fetch::state: Fetched data for 2 remote(s) (155ms) 1792585597 worker#0 radicle_fetch::state: Fetched data refs for 0 remotes (252ms) 1792585597 worker#0 radicle_fetch::state: Sent done signal to remote z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585597 worker#0 radicle_fetch::state: Pruning delegate z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk tips, missing 'rad/sigrefs' 1792585597 worker#0 radicle_fetch::state: Validated 1 remote(s) (252ms) 1792585597 service wire: Received `end-of-file` on stream 13 from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585597 upload-pack worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585597 worker#4 worker: Upload pack finished (403ms) 1792585597 worker#4 worker: Upload process on stream 13 exited with result Ok(()) 1792585597 service wire: Stream 13 of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk closing with 0 byte(s) sent and 0 byte(s) received 1792585597 service wire: Peer z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully 1792585598 service wire: Received `close` command for stream 13 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585598 service wire: Stream 13 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closed with 0 byte(s) sent and 0 byte(s) received 1792585598 worker#0 radicle_fetch::state: Applied updates (359ms) 1792585598 worker#0 radicle_fetch: Finished clone of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (524ms) 1792585598 worker#0 worker: Validation error: missing `refs/namespaces/z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk/refs/rad/sigrefs` 1792585598 worker#0 storage: Obtained identity COB root commit '0656c217f917c3e06234771e9ecae53aba5e173e' via reference 'refs/rad/root' in namespace of founder 'did:key:z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi' of identity document 'rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji'. 1792585598 worker#0 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585598 worker#0 storage: Setting ref: refs/rad/id -> 7be665f9fccba97abb21b2fa85a6fd3181c72858 1792585598 worker#0 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585598 worker#0 cob: Loading object of type xyz.radicle.patch at b09b2aa0ee055671c811e9ad4ba73eed211ebaa3. 1792585598 service service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi successfully 1792585598 service service: Ref updated: * 0000000..7be665f refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/id for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 service service: Ref updated: * 0000000..e5c2007 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/sigrefs for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 service service: Ref updated: * 0000000..7be665f refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.id/0656c217f917c3e06234771e9ecae53aba5e173e for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 service service: Ref updated: * 0000000..b09b2aa refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/cobs/xyz.radicle.patch/b09b2aa0ee055671c811e9ad4ba73eed211ebaa3 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 service service: Ref updated: * 0000000..3e674d1 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/master for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 service service: Ref updated: * 0000000..964513c refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/heads/patches/b09b2aa0ee055671c811e9ad4ba73eed211ebaa3 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 service service: Ref updated: * 0000000..0656c21 refs/namespaces/z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi/refs/rad/root for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 service service: Updating and announcing inventory for cloned repository rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji.. 1792585598 test: rad-id-threshold:191: `cd /build/.tmpbfUcXD/bob/work/heartwood`.. 1792585598 test: rad-id-threshold:192: `rad fork` @ /build/.tmpbfUcXD/bob/work/heartwood 1792585598 test: rad-id-threshold:197: `rad sync -f` @ /build/.tmpbfUcXD/alice/work 1792585598 control control: Received `{"command":"status"}` on control socket 1792585598 control control: Received `{"command":"seedsFor","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","namespaces":["z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi"]}` on control socket 1792585598 service service: Received command Seeds(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji) 1792585598 service service: Found 1 connected seed(s) and 1 disconnected seed(s) for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 control control: Received `{"command":"session","nid":"z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z"}` on control socket 1792585598 service service: Received command QueryState(..) 1792585598 control control: Received `{"command":"fetch","rid":"rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji","nid":"z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z","timeout":{"secs":9,"nanos":0},"signedReferencesMinimumFeatureLevel":null}` on control socket 1792585598 service service: Received command Fetch(rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z) 1792585598 service service: Starting fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585598 service service: Fetch initiated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z [outbound connected] (all remotes).. 1792585598 service wire: Opened new stream with id 12 for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji and remote z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585598 worker#5 worker: Worker processing outgoing fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 service wire: Received `open` command for stream 12 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585598 worker#4 worker: Worker processing incoming fetch for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi on stream 12.. 1792585598 worker#5 worker: z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi pulling from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585598 worker#4 worker: Spawning upload-pack process for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji on stream 12.. 1792585598 service service: Sending inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 1 item(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585598231) 1792585598 service service: Sending inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 1 item(s) to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585598231) 1792585598 worker#5 radicle_fetch::state: Fetched rad/id (27ms) 1792585598 worker#5 radicle_fetch::state: Fetched data for 1 remote(s) (28ms) 1792585598 worker#5 radicle_fetch::state: Fetched data refs for 0 remotes (28ms) 1792585598 worker#5 radicle_fetch::state: Sent done signal to remote z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585598 service wire: Received `end-of-file` on stream 12 from z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi 1792585598 upload-pack worker: Exiting upload-pack reader thread for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji 1792585598 worker#4 worker: Upload pack finished (96ms) 1792585598 worker#4 worker: Upload process on stream 12 exited with result Ok(()) 1792585598 worker#5 radicle_fetch::state: Pruning delegate z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk tips, missing 'rad/sigrefs' 1792585598 worker#5 radicle_fetch::state: Validated 0 remote(s) (49ms) 1792585598 worker#5 radicle_fetch::state: Applied updates (49ms) 1792585598 service wire: Stream 12 of z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi closing with 0 byte(s) sent and 0 byte(s) received 1792585598 worker#5 radicle_fetch: Finished pull of rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji (99ms) 1792585598 service wire: Peer z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from us successfully 1792585598 worker#5 worker: Validation error: missing `refs/namespaces/z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk/refs/rad/sigrefs` 1792585598 service wire: Received `close` command for stream 12 from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585598 service wire: Stream 12 of z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z closed with 0 byte(s) sent and 0 byte(s) received 1792585598 worker#5 storage: Obtained identity COB root commit '0656c217f917c3e06234771e9ecae53aba5e173e' via reference 'refs/rad/root' in namespace of founder 'did:key:z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi' of identity document 'rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji'. 1792585598 worker#5 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585598 service service: Sending refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi (t=1792585598232) 1792585598 service service: Sending refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) to z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z (t=1792585598232) 1792585598 worker#5 storage: Setting ref: refs/rad/id -> 7be665f9fccba97abb21b2fa85a6fd3181c72858 1792585598 service reactor: Service was busy 643.051057ms which exceeds the timeout of 100ms 1792585598 service service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 1 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585598231) 1792585598 service service: Received inventory announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk with 1 item(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585598231) 1792585598 worker#5 cob: Loading object of type xyz.radicle.id at 0656c217f917c3e06234771e9ecae53aba5e173e. 1792585598 service service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 875 millisecond(s) (t=1792585598231) 1792585598 service service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585598 service service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 3.23 second(s) (t=1792585598231) 1792585598 service service: Received refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585598232) 1792585598 service service: Routing table updated for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with seed z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585598 service service: Received refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji with 1 remote(s) from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk (t=1792585598232) 1792585598 service service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 875 millisecond(s) (t=1792585598232) 1792585598 service service: Stored announcement from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk to be broadcast in 3.23 second(s) (t=1792585598232) 1792585598 service service: Refs announcement of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji contains our own remote at e5c200775dc6da38306f62c995df67a1ab1bf62d (t=1792585598232) 1792585598 service service: Skipping fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all refs are already in storage 1792585598 service reactor: Service was busy 123.656014ms which exceeds the timeout of 100ms 1792585599 service service: Updating sync status of z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji to e5c200775dc6da38306f62c995df67a1ab1bf62d 1792585599 service service: Skipping fetch for rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji, all refs are already in storage 1792585599 service reactor: Service was busy 151.93397ms which exceeds the timeout of 100ms 1792585599 service service: Fetched rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z successfully 1792585599 service service: Nothing to announce, no refs were updated.. thread 'commands::id::rad_id_threshold' (18220) panicked at crates/radicle-cli-test/src/lib.rs:504:36: --- Expected ++++ actual: stdout 1 - Fetching rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from the network, found 2 potential seed(s). 2 - ✓ Target met: 2 seed(s) 1 + Fetching rad:z42hL2jL4XNk6K8oHQaSWfMgCL7ji from the network, found 1 potential seed(s). 2 + ✓ Target met: 1 seed(s) 3 3 | 🌱 Fetched from z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 4 - 🌱 Fetched from z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace 1792585599 test: Node z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk shutting down.. 1792585599 commands::id::rad_id_threshold reactor::controller: Initiating reactor shutdown… 1792585599 control control: Received `{"command":"shutdown"}` on control socket 1792585599 service reactor: Shutdown 1792585599 control control: Shutdown requested.. 1792585599 runtime pool: Worker pool shutting down.. 1792585599 runtime node: Node shutdown completed for z6Mkt67GdsW7715MEfRuP4pSZxJRJh6kj6Y48WRqVv4N1tRk 1792585599 test: Node z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z shutting down.. 1792585599 commands::id::rad_id_threshold reactor::controller: Initiating reactor shutdown… 1792585599 service reactor: Shutdown 1792585599 control control: Received `{"command":"shutdown"}` on control socket 1792585599 control control: Shutdown requested.. 1792585599 runtime pool: Worker pool shutting down.. 1792585599 runtime node: Node shutdown completed for z6Mkux1aUQD2voWWukVb5nNUR7thrHveQG4pDQua8nVhib7Z 1792585599 test: Node z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi shutting down.. 1792585599 commands::id::rad_id_threshold reactor::controller: Initiating reactor shutdown… 1792585599 control control: Received `{"command":"shutdown"}` on control socket 1792585599 control control: Shutdown requested.. 1792585599 service reactor: Shutdown 1792585599 runtime pool: Worker pool shutting down.. 1792585599 runtime node: Node shutdown completed for z6MknSLrJoTcukLrE435hVNQT4JUhbvWLX4kUzqkEStBU8Vi failures: commands::id::rad_id_threshold test result: FAILED. 120 passed; 1 failed; 3 ignored; 0 measured; 2 filtered out; finished in 119.83s error: test failed, to rerun pass `-p radicle-cli --test commands`