Log from Radicle native CI

Run: `git clone /home/_rad/.radicle/storage/zwTxygwuz5LDGBq255RA2CbNGrz8 /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src`

Started: 2024-08-29 10:15:30Z
Ended: 2024-08-29 10:15:30Z
Duration: 0 s

Command arguments:

"git"
"clone"
"/home/_rad/.radicle/storage/zwTxygwuz5LDGBq255RA2CbNGrz8"
"/srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src"

In directory: /

Exit code: 0

Output (stdout and stderr):

Cloning into '/srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src'...
done.

Run: `git config advice.detachedHead false`

Started: 2024-08-29 10:15:30Z
Ended: 2024-08-29 10:15:30Z
Duration: 0 s

Command arguments:

"git"
"config"
"advice.detachedHead"
"false"

In directory: /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src

Exit code: 0

Run: `git checkout e82503bf951280285bf5f7decb5af9968bccfce2`

Started: 2024-08-29 10:15:30Z
Ended: 2024-08-29 10:15:30Z
Duration: 0 s

Command arguments:

"git"
"checkout"
"e82503bf951280285bf5f7decb5af9968bccfce2"

In directory: /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src

Exit code: 0

Output (stdout and stderr):

HEAD is now at e82503b fix(ci-broker.md): mock node control socket handling in helper

Run: `git show e82503bf951280285bf5f7decb5af9968bccfce2`

Started: 2024-08-29 10:15:30Z
Ended: 2024-08-29 10:15:30Z
Duration: 0 s

Command arguments:

"git"
"show"
"e82503bf951280285bf5f7decb5af9968bccfce2"

In directory: /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src

Exit code: 0

Output (stdout and stderr):

commit e82503bf951280285bf5f7decb5af9968bccfce2
Author: Lars Wirzenius <liw@liw.fi>
Date:   Tue Aug 27 16:35:14 2024 +0300

    fix(ci-broker.md): mock node control socket handling in helper
    
    The test suite didn't work on Macs. A fundamental problem was using
    "sleep 0.1" to wait for a background process to do something: this is
    never a good idea. Either the wait time is too short, resulting in
    spurious test failures, or it's so long it makes test take
    unnecessarily long. I should have known better. A better approach is
    to loop for a sufficiently long time and check if the background
    process has done its thing every so often, and stop looping when it
    has.
    
    There were some other, more incidental incompatibility problems too.
    
    To fix all this, replace use of "nc", a common tool with many
    incompatible variants, by adding a "client mode" to
    synthetic-events.rs that connects to the daemon and closes the
    connection neatly, then waits for the daemon to delete the socket.
    Also, change how it does what it does so that it works both Linux and
    Mac. This includes removing setting a read timeout that failed on Mac,
    but doesn't seem to be necessary now.
    
    Also, change synthetic-events to log more and more detailed messages
    to aid remote debugging of test suite failures in the future. The
    daemon logs errors from fallible operations, instead of discarding
    them and terminating, and the client outputs the daemon log if the
    client fails, while waiting for the daemon to remove the socket.
    
    Finally, during the furious debugging, refactor the code into smaller
    functions so its easier to understand when nothing makes sense.
    
    Signed-off-by: Lars Wirzenius <liw@liw.fi>

diff --git a/ci-broker.md b/ci-broker.md
index 5911c24..1693640 100644
--- a/ci-broker.md
+++ b/ci-broker.md
@@ -581,27 +581,26 @@ scenarios.
 
 _Who:_ `cib-devs`
 
-The following scenario may only work on Linux, as it's using `pgrep`
-and `nc` and those may not be portable. If so, this may need to be
-changed for other platforms.
+We use the `synthetic-events --client` option to connect to the daemon
+and wait for the daemon to delete the socket file. This is more
+easily portable than using a generic tool such as `nc`, which has many
+variants across operating systems.
 
-Note also that the version of `nc` must support the `-U` option, which
-in Debian means using the `netcat-openbsd` package.
-
-We sleep for a very short time to make sure the `synthetic-events`
-daemon has time to remove the socket file before we check that it's
-been deleted.
+We wait for up to ten seconds the `synthetic-events` daemon to remove
+the socket file before we check that it's been deleted, but checking
+for that every second. This avoids the trap of waiting for a fixed
+time: if the time is too short, the scenario fails spuriously, and if
+it's very long, the scenario takes longer than necessary.
 
 ~~~scenario
 given an installed synthetic-events
 
 then file synt.sock does not exist
 
-when I run synthetic-events synt.sock
+when I run synthetic-events --log daemon.log synt.sock
 then file synt.sock exists
 
-when I run nc -U synt.sock
-when I run sleep 0.1
+when I run synthetic-events --client --log daemon.log synt.sock
 then file synt.sock does not exist
 ~~~
 
diff --git a/src/bin/synthetic-events.rs b/src/bin/synthetic-events.rs
index 29f3f97..566a315 100644
--- a/src/bin/synthetic-events.rs
+++ b/src/bin/synthetic-events.rs
@@ -10,42 +10,103 @@ use std::{
     fs::{read, remove_file, File, OpenOptions},
     io::{Read, Write},
     net::Shutdown,
-    os::unix::net::UnixListener,
+    os::unix::net::{UnixListener, UnixStream},
     path::PathBuf,
     process::{Command, Stdio},
     thread::sleep,
-    time::Duration,
+    time::{Duration, Instant},
 };
 
 use clap::Parser;
 
 use radicle::node::Event;
 
+const MAX_WAIT: Duration = Duration::from_secs(10);
+
 fn log(file: &mut File, msg: String) {
     file.write_all(msg.as_bytes()).ok();
 }
 
 fn main() -> anyhow::Result<()> {
     let args = Args::parse();
-    let exe = current_exe()?;
 
-    if args.daemon {
-        let mut f = OpenOptions::new()
-            .create(true)
-            .append(true)
-            .open(args.log.unwrap())?;
+    if let Err(e) = {
+        if args.client {
+            client(&args)
+        } else if args.daemon {
+            daemon(&args)
+        } else {
+            launch_daemon(&args)
+        }
+    } {
+        eprintln!("ERROR: {e}");
+        let mut e = e.source();
+        while let Some(underlying) = e {
+            eprintln!("caused by: {underlying}");
+            e = underlying.source();
+        }
+    }
 
-        log(&mut f, "daemon starts\n".into());
+    Ok(())
+}
+
+#[derive(Debug, Parser)]
+struct Args {
+    #[clap(long)]
+    client: bool,
+    #[clap(long)]
+    daemon: bool,
+    #[clap(long)]
+    log: Option<PathBuf>,
+    #[clap(long)]
+    repeat: Option<usize>,
+    socket: PathBuf,
+    events: Vec<PathBuf>,
+}
+
+fn client(args: &Args) -> anyhow::Result<()> {
+    println!("connect to {}", args.socket.display());
+    {
+        let mut stream = UnixStream::connect(&args.socket).unwrap();
+        stream.shutdown(Shutdown::Write)?;
+        let mut buf = vec![];
+        stream.read_to_end(&mut buf)?;
+    }
+
+    println!("wait for daemon to remove the socket file");
+    let started = Instant::now();
+    while args.socket.exists() {
+        let elapsed = started.elapsed();
+        println!(
+            "still waiting; it's been {} milliseconds",
+            elapsed.as_millis()
+        );
+        if elapsed > MAX_WAIT {
+            if let Some(filename) = args.log.as_ref() {
+                let log = std::fs::read(filename)?;
+                let log = String::from_utf8_lossy(&log);
+                eprintln!("daemon log {}:\n====\n{log}\n----\n", filename.display());
+            }
+            panic!(
+                "waited too long for {} to be deleted",
+                args.socket.display()
+            );
+        }
+        sleep(Duration::from_secs(1));
+    }
+    Ok(())
+}
+
+fn daemon(args: &Args) -> anyhow::Result<()> {
+    fn helper(args: &Args, f: &mut File) -> anyhow::Result<()> {
+        log(f, "daemon starts\n".into());
 
         let repeat = args.repeat.unwrap_or(1);
-        log(&mut f, format!("repeat={repeat}\n"));
+        log(f, format!("repeat={repeat}\n"));
 
         let mut events = vec![];
         for filename in args.events.iter() {
-            log(
-                &mut f,
-                format!("daemon reads file {}\n", filename.display()),
-            );
+            log(f, format!("daemon reads file {}\n", filename.display()));
 
             let data = read(filename)?;
             let e: Event = serde_json::from_slice(&data)?;
@@ -54,71 +115,79 @@ fn main() -> anyhow::Result<()> {
             events.push(e);
         }
 
-        log(
-            &mut f,
-            format!("daemon connects to {}\n", args.socket.display()),
-        );
+        log(f, format!("daemon connects to {}\n", args.socket.display()));
         let listener = UnixListener::bind(&args.socket)?;
 
         if let Some(stream) = listener.incoming().next() {
-            log(&mut f, "daemon accepted connection\n".into());
+            log(f, format!("daemon accepted connection: {stream:?}\n"));
             let mut stream = stream?;
+            log(f, format!("unwrapped stream: {stream:?}"));
             for e in events.iter() {
+                log(f, "iterated to an event\n".into());
                 for _ in 0..repeat {
                     stream.write_all(e.as_bytes())?;
                 }
-                log(&mut f, "daemon sent a message\n".into());
+                log(f, "daemon sent a message\n".into());
             }
-            log(&mut f, "daemon sent all messages\n".into());
+            log(f, "daemon sent all messages\n".into());
 
+            log(f, "daemon shutting down write end\n".into());
             stream.shutdown(Shutdown::Write)?;
-            log(&mut f, "daemon shutdown write\n".into());
+            log(f, "daemon shutdown write\n".into());
 
-            stream.set_read_timeout(Some(Duration::from_millis(1000)))?;
+            // log(f, "daemon set socket read timeout\n".into());
+            // stream.set_read_timeout(Some(Duration::from_millis(1000)))?;
             let mut buf = vec![];
+            log(f, "daemon read from socket\n".into());
             stream.read_to_end(&mut buf).ok();
-            log(&mut f, "daemon finished reading\n".into());
+            log(f, "daemon finished reading\n".into());
         }
 
+        log(f, "daemon removes socket file\n".into());
         remove_file(&args.socket)?;
-        log(&mut f, "daemon ends\n".into());
-    } else {
-        if args.socket.exists() {
-            eprintln!("removing socket {}", args.socket.display());
-            remove_file(&args.socket)?;
-        }
-        eprintln!("launching daemon");
-        let repeat = args.repeat.unwrap_or(1);
-        Command::new(exe)
-            .arg("--daemon")
-            .arg("--log")
-            .arg(args.log.unwrap_or(PathBuf::from("/dev/null")))
-            .arg(&format!("--repeat={repeat}"))
-            .arg(&args.socket)
-            .args(&args.events)
-            .stdin(Stdio::null())
-            .stdout(Stdio::null())
-            .stderr(Stdio::null())
-            .spawn()?;
-        eprintln!("waiting for daemon to create socket");
-        while !args.socket.exists() {
-            eprintln!("no socket yet");
-            sleep(Duration::from_millis(100));
-        }
-        eprintln!("there is a socket now");
+
+        log(f, "daemon ends\n".into());
+
+        Ok(())
+    }
+
+    let mut f = OpenOptions::new()
+        .create(true)
+        .append(true)
+        .open(args.log.as_ref().unwrap())?;
+
+    if let Err(e) = helper(args, &mut f) {
+        log(&mut f, format!("ERROR: {e}"));
     }
 
     Ok(())
 }
 
-#[derive(Debug, Parser)]
-struct Args {
-    #[clap(long)]
-    daemon: bool,
-    #[clap(long)]
-    log: Option<PathBuf>,
-    #[clap(long)]
-    repeat: Option<usize>,
-    socket: PathBuf,
-    events: Vec<PathBuf>,
+fn launch_daemon(args: &Args) -> anyhow::Result<()> {
+    if args.socket.exists() {
+        println!("removing socket {}", args.socket.display());
+        remove_file(&args.socket)?;
+    }
+    println!("launching daemon");
+    let repeat = args.repeat.unwrap_or(1);
+    let exe = current_exe()?;
+    Command::new(exe)
+        .arg("--daemon")
+        .arg("--log")
+        .arg(args.log.clone().unwrap_or(PathBuf::from("/dev/null")))
+        .arg(&format!("--repeat={repeat}"))
+        .arg(&args.socket)
+        .args(&args.events)
+        .stdin(Stdio::null())
+        .stdout(Stdio::null())
+        .stderr(Stdio::null())
+        .spawn()?;
+    println!("waiting for daemon to create socket");
+    while !args.socket.exists() {
+        println!("no socket yet");
+        sleep(Duration::from_millis(100));
+    }
+    println!("there is a socket now");
+
+    Ok(())
 }

Run: `timeout 600 bash -c set -xeuo pipefail cargo --version rustc --version cargo fmt --check cargo clippy --all-targets --workspace -- -Dwarnings cargo build --all-targets --workspace cargo doc --workspace cargo test --workspace --no-fail-fast subplot docgen ci-broker.subplot -o doc/ci-broker.html make -C doc publish`

Started: 2024-08-29 10:15:30Z
Ended: 2024-08-29 10:17:57Z
Duration: 146 s

Command arguments:

"timeout"
"600"
"bash"
"-c"
"set -xeuo pipefail\ncargo --version\nrustc --version\n\ncargo fmt --check\ncargo clippy --all-targets --workspace -- -Dwarnings\ncargo build --all-targets --workspace\ncargo doc --workspace\ncargo test --workspace --no-fail-fast\n\nsubplot docgen ci-broker.subplot -o doc/ci-broker.html\nmake -C doc publish\n"

In directory: /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src

Exit code: 101

Output (stdout and stderr):

+ cargo --version
cargo 1.80.1 (376290515 2024-07-16)
+ rustc --version
rustc 1.80.1 (3f5fd8dd4 2024-08-06)
+ cargo fmt --check
+ cargo clippy --all-targets --workspace -- -Dwarnings
   Compiling proc-macro2 v1.0.86
   Compiling unicode-ident v1.0.12
   Compiling version_check v0.9.5
   Compiling libc v0.2.158
   Compiling typenum v1.17.0
    Checking cfg-if v1.0.0
   Compiling shlex v1.3.0
   Compiling serde v1.0.209
   Compiling thiserror v1.0.63
   Compiling memchr v2.7.4
   Compiling once_cell v1.19.0
   Compiling syn v1.0.109
   Compiling byteorder v1.5.0
    Checking subtle v2.6.1
   Compiling bitflags v2.6.0
   Compiling crossbeam-utils v0.8.20
   Compiling rustix v0.38.34
   Compiling pkg-config v0.3.30
   Compiling anyhow v1.0.86
   Compiling linux-raw-sys v0.4.14
   Compiling itoa v1.0.11
   Compiling generic-array v0.14.7
   Compiling ahash v0.8.11
   Compiling regex-syntax v0.8.4
   Compiling log v0.4.22
    Checking cpufeatures v0.2.13
   Compiling num-conv v0.1.0
   Compiling same-file v1.0.6
   Compiling utf8parse v0.2.2
   Compiling time-core v0.1.2
   Compiling allocator-api2 v0.2.18
   Compiling anstyle-parse v0.2.5
   Compiling aho-corasick v1.1.3
   Compiling walkdir v2.5.0
   Compiling time-macros v0.2.18
   Compiling colorchoice v1.0.2
   Compiling is_terminal_polyfill v1.70.1
   Compiling ucd-trie v0.1.6
   Compiling anstyle-query v1.1.1
   Compiling anstyle v1.0.8
   Compiling bstr v1.10.0
   Compiling quote v1.0.37
   Compiling unic-char-range v0.9.0
   Compiling powerfmt v0.2.0
   Compiling heck v0.5.0
   Compiling anstream v0.6.15
   Compiling libm v0.2.8
   Compiling unic-common v0.9.0
   Compiling syn v2.0.76
   Compiling jobserver v0.1.32
   Compiling getrandom v0.2.15
   Compiling crossbeam-epoch v0.9.18
   Compiling doc-comment v0.3.3
   Compiling vcpkg v0.2.15
   Compiling unicode-width v0.1.13
   Compiling cc v1.1.14
   Compiling rand_core v0.6.4
   Compiling unic-ucd-version v0.9.0
   Compiling deranged v0.3.11
   Compiling unic-char-property v0.9.0
   Compiling crossbeam-deque v0.8.5
   Compiling proc-macro-error-attr v1.0.4
   Compiling unicase v2.7.0
   Compiling encoding_rs v0.8.34
   Compiling fastrand v2.1.1
   Compiling arraydeque v0.5.1
   Compiling clap_lex v0.7.2
   Compiling strsim v0.11.1
    Checking tinyvec_macros v0.1.1
   Compiling serde_json v1.0.127
    Checking tinyvec v1.8.0
    Checking crypto-common v0.1.6
    Checking block-padding v0.3.3
    Checking block-buffer v0.10.4
    Checking universal-hash v0.5.1
    Checking inout v0.1.3
    Checking digest v0.10.7
   Compiling clap_builder v4.5.15
   Compiling unic-ucd-segment v0.9.0
    Checking cipher v0.4.4
   Compiling proc-macro-error v1.0.4
    Checking signature v1.6.4
   Compiling pulldown-cmark v0.12.0
   Compiling deunicode v1.6.0
   Compiling tempfile v3.12.0
   Compiling time v0.3.36
   Compiling regex-automata v0.4.7
   Compiling smawk v0.3.2
   Compiling ryu v1.0.18
   Compiling amplify_syn v2.0.1
   Compiling unicode-linebreak v0.1.5
    Checking opaque-debug v0.3.1
   Compiling humansize v2.1.3
    Checking unicode-normalization v0.1.23
   Compiling slug v0.1.6
   Compiling unic-segment v0.9.0
   Compiling textwrap v0.16.1
    Checking ed25519 v1.5.3
   Compiling getopts v0.2.21
   Compiling subplot v0.11.0
   Compiling humantime v2.1.0
   Compiling libz-sys v1.1.20
   Compiling pikchr v0.1.3
   Compiling libgit2-sys v0.16.2+1.7.2
   Compiling pulldown-cmark-escape v0.11.0
    Checking amplify_num v0.5.3
    Checking ct-codecs v1.1.1
    Checking percent-encoding v2.3.1
   Compiling lazy_static v1.5.0
    Checking unicode-bidi v0.3.15
    Checking ascii v1.1.0
   Compiling utf8-width v0.1.7
    Checking form_urlencoded v1.2.1
   Compiling html-escape v0.2.13
    Checking ec25519 v0.1.0
   Compiling sqlite3-src v0.5.1
    Checking polyval v0.6.2
    Checking idna v0.5.0
    Checking sha2 v0.10.8
   Compiling amplify_derive v4.0.1
   Compiling tracing-core v0.1.32
    Checking base64ct v1.6.0
   Compiling base64 v0.22.1
   Compiling file_diff v1.0.0
   Compiling line-col v0.2.1
    Checking keccak v0.1.5
   Compiling pin-project-lite v0.2.14
    Checking pem-rfc7468 v0.7.0
    Checking url v2.5.2
    Checking ghash v0.5.1
    Checking aes v0.8.4
    Checking sha3 v0.10.8
    Checking ctr v0.9.2
   Compiling globset v0.4.14
   Compiling regex v1.10.6
    Checking aead v0.5.2
   Compiling cfg_aliases v0.2.1
    Checking hashbrown v0.14.5
    Checking equivalent v1.0.1
   Compiling data-encoding v2.6.0
    Checking base32 v0.4.0
   Compiling nix v0.29.0
   Compiling ignore v0.4.22
    Checking aes-gcm v0.10.3
    Checking ssh-encoding v0.2.0
   Compiling env_filter v0.1.2
    Checking blowfish v0.9.1
    Checking poly1305 v0.8.0
   Compiling env_logger v0.11.5
    Checking indexmap v2.4.0
    Checking chacha20 v0.9.1
    Checking cbc v0.1.2
    Checking pbkdf2 v0.12.2
   Compiling adler2 v2.0.0
   Compiling globwalk v0.9.1
    Checking radicle-std-ext v0.1.0
    Checking zeroize v1.8.1
    Checking ssh-cipher v0.2.0
    Checking bcrypt-pbkdf v0.10.0
   Compiling miniz_oxide v0.8.0
   Compiling data-encoding-macro-internal v0.1.13
    Checking rand v0.8.5
   Compiling xattr v1.3.1
   Compiling filetime v0.2.24
   Compiling crc32fast v1.4.2
   Compiling slog v2.7.0
    Checking signature v2.2.0
    Checking base-x v0.2.11
   Compiling tar v0.4.41
    Checking ssh-key v0.6.6
   Compiling flate2 v1.0.33
    Checking qcheck v1.0.0
    Checking cvt v0.1.2
    Checking data-encoding-macro v0.1.15
    Checking multibase v0.9.1
   Compiling glob v0.3.1
    Checking terminal_size v0.3.0
    Checking radicle-dag v0.9.0
   Compiling serde_yml v0.0.11
    Checking normpath v1.3.0
    Checking amplify v4.7.0
    Checking fs2 v0.4.3
   Compiling radicle-surf v0.18.0
    Checking libyml v0.0.4
    Checking state v0.5.3
    Checking unescape v0.1.0
    Checking nonempty v0.5.0
    Checking siphasher v1.0.1
    Checking fs_at v0.2.1
    Checking crossbeam-channel v0.5.13
    Checking arc-swap v1.7.1
    Checking base64 v0.21.7
    Checking remove_dir_all v0.8.3
    Checking shell-words v1.1.0
    Checking cyphergraphy v0.3.0
    Checking base64 v0.13.1
    Checking uuid v1.10.0
    Checking slog-scope v4.4.0
    Checking cypheraddr v0.4.0
   Compiling serde_derive v1.0.209
   Compiling thiserror-impl v1.0.63
   Compiling zerocopy-derive v0.7.35
   Compiling clap_derive v4.5.13
   Compiling git-testament-derive v0.2.0
   Compiling tracing-attributes v0.1.27
   Compiling culpa-macros v1.0.2
    Checking socks5-client v0.4.1
    Checking html-page v0.4.0
   Compiling ctor v0.2.8
    Checking cyphernet v0.5.2
   Compiling git-testament v0.2.5
   Compiling zerocopy v0.7.35
   Compiling pest v2.7.11
   Compiling git-ref-format-core v0.3.0
   Compiling culpa v1.0.2
   Compiling tracing v0.1.40
    Checking radicle-ssh v0.9.0
   Compiling subplotlib-derive v0.11.0
   Compiling git-ref-format-macro v0.3.0
   Compiling ppv-lite86 v0.2.20
   Compiling clap v4.5.16
   Compiling rand_chacha v0.3.1
   Compiling hashlink v0.8.4
   Compiling hashlink v0.9.1
   Compiling pest_meta v2.7.11
   Compiling yaml-rust2 v0.8.1
   Compiling tempfile-fast v0.3.4
   Compiling pest_generator v2.7.11
   Compiling pest_derive v2.7.11
    Checking nonempty v0.9.0
    Checking localtime v1.3.1
    Checking git-ref-format v0.3.0
   Compiling serde_path_to_error v0.1.16
    Checking slog-json v2.6.1
   Compiling marked-yaml v0.7.1
   Compiling roadmap v0.6.1
   Compiling tera v1.20.0
    Checking git2 v0.18.3
    Checking radicle-git-ext v0.7.0
   Compiling subplot-build v0.11.0
   Compiling subplotlib v0.11.0
   Compiling radicle-ci-broker v0.5.0 (/srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src)
    Checking sqlite3-sys v0.15.2
    Checking sqlite v0.32.0
    Checking radicle-crypto v0.10.0
    Checking radicle-cob v0.11.0
    Checking radicle v0.12.0
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 20.73s
+ cargo build --all-targets --workspace
   Compiling cfg-if v1.0.0
   Compiling bitflags v2.6.0
   Compiling memchr v2.7.4
   Compiling once_cell v1.19.0
   Compiling log v0.4.22
   Compiling itoa v1.0.11
   Compiling subtle v2.6.1
   Compiling typenum v1.17.0
   Compiling libc v0.2.158
   Compiling regex-syntax v0.8.4
   Compiling fastrand v2.1.1
   Compiling percent-encoding v2.3.1
   Compiling serde v1.0.209
   Compiling thiserror v1.0.63
   Compiling utf8parse v0.2.2
   Compiling crossbeam-utils v0.8.20
   Compiling colorchoice v1.0.2
   Compiling anstyle-parse v0.2.5
   Compiling is_terminal_polyfill v1.70.1
   Compiling cpufeatures v0.2.13
   Compiling anstyle-query v1.1.1
   Compiling rustix v0.38.34
   Compiling ryu v1.0.18
   Compiling anstyle v1.0.8
   Compiling ahash v0.8.11
   Compiling anyhow v1.0.86
   Compiling powerfmt v0.2.0
   Compiling time-core v0.1.2
   Compiling num-conv v0.1.0
   Compiling getrandom v0.2.15
   Compiling clap_lex v0.7.2
   Compiling strsim v0.11.1
   Compiling anstream v0.6.15
   Compiling hashbrown v0.14.5
   Compiling time-macros v0.2.18
   Compiling deranged v0.3.11
   Compiling rand_core v0.6.4
   Compiling encoding_rs v0.8.34
   Compiling aho-corasick v1.1.3
   Compiling pest v2.7.11
   Compiling bstr v1.10.0
   Compiling generic-array v0.14.7
   Compiling crossbeam-epoch v0.9.18
   Compiling rand_chacha v0.3.1
   Compiling tinyvec_macros v0.1.1
   Compiling tinyvec v1.8.0
   Compiling crossbeam-deque v0.8.5
   Compiling hashlink v0.8.4
   Compiling hashlink v0.9.1
   Compiling rand v0.8.5
   Compiling clap_builder v4.5.15
   Compiling lazy_static v1.5.0
   Compiling utf8-width v0.1.7
   Compiling crypto-common v0.1.6
   Compiling block-buffer v0.10.4
   Compiling block-padding v0.3.3
   Compiling inout v0.1.3
   Compiling digest v0.10.7
   Compiling universal-hash v0.5.1
   Compiling signature v1.6.4
   Compiling cipher v0.4.4
   Compiling opaque-debug v0.3.1
   Compiling ed25519 v1.5.3
   Compiling html-escape v0.2.13
   Compiling subplot v0.11.0
   Compiling ct-codecs v1.1.1
   Compiling unicode-bidi v0.3.15
   Compiling linux-raw-sys v0.4.14
   Compiling yaml-rust2 v0.8.1
   Compiling unicode-normalization v0.1.23
   Compiling ascii v1.1.0
   Compiling pest_meta v2.7.11
   Compiling tempfile v3.12.0
   Compiling regex-automata v0.4.7
   Compiling amplify_num v0.5.3
   Compiling time v0.3.36
   Compiling tempfile-fast v0.3.4
   Compiling idna v0.5.0
   Compiling ec25519 v0.1.0
   Compiling pulldown-cmark v0.12.0
   Compiling sha2 v0.10.8
   Compiling polyval v0.6.2
   Compiling libz-sys v1.1.20
   Compiling pest_generator v2.7.11
   Compiling tracing-core v0.1.32
   Compiling form_urlencoded v1.2.1
   Compiling git-ref-format-core v0.3.0
   Compiling keccak v0.1.5
   Compiling base64ct v1.6.0
   Compiling serde_path_to_error v0.1.16
   Compiling serde_json v1.0.127
   Compiling base64 v0.22.1
   Compiling byteorder v1.5.0
   Compiling clap v4.5.16
   Compiling git-ref-format-macro v0.3.0
   Compiling tracing v0.1.40
   Compiling pem-rfc7468 v0.7.0
   Compiling marked-yaml v0.7.1
   Compiling sha3 v0.10.8
   Compiling amplify v4.7.0
   Compiling url v2.5.2
   Compiling git-testament-derive v0.2.0
   Compiling ghash v0.5.1
   Compiling libgit2-sys v0.16.2+1.7.2
   Compiling roadmap v0.6.1
   Compiling aes v0.8.4
   Compiling pest_derive v2.7.11
   Compiling cyphergraphy v0.3.0
   Compiling ctr v0.9.2
   Compiling aead v0.5.2
   Compiling base32 v0.4.0
   Compiling equivalent v1.0.1
   Compiling globset v0.4.14
   Compiling regex v1.10.6
   Compiling cypheraddr v0.4.0
   Compiling git2 v0.18.3
   Compiling git-ref-format v0.3.0
   Compiling aes-gcm v0.10.3
   Compiling sqlite3-src v0.5.1
   Compiling ssh-encoding v0.2.0
   Compiling blowfish v0.9.1
   Compiling chacha20 v0.9.1
   Compiling cbc v0.1.2
   Compiling ignore v0.4.22
   Compiling env_filter v0.1.2
   Compiling pbkdf2 v0.12.2
   Compiling poly1305 v0.8.0
   Compiling data-encoding v2.6.0
   Compiling radicle-std-ext v0.1.0
   Compiling zeroize v1.8.1
   Compiling bcrypt-pbkdf v0.10.0
   Compiling indexmap v2.4.0
   Compiling env_logger v0.11.5
   Compiling socks5-client v0.4.1
   Compiling sqlite3-sys v0.15.2
   Compiling git-testament v0.2.5
   Compiling xattr v1.3.1
   Compiling ssh-cipher v0.2.0
   Compiling filetime v0.2.24
   Compiling crc32fast v1.4.2
   Compiling base-x v0.2.11
   Compiling data-encoding-macro v0.1.15
   Compiling signature v2.2.0
   Compiling multibase v0.9.1
   Compiling flate2 v1.0.33
   Compiling tar v0.4.41
   Compiling cyphernet v0.5.2
   Compiling sqlite v0.32.0
   Compiling ssh-key v0.6.6
   Compiling nix v0.29.0
   Compiling radicle-ssh v0.9.0
   Compiling globwalk v0.9.1
   Compiling qcheck v1.0.0
   Compiling tera v1.20.0
   Compiling cvt v0.1.2
   Compiling culpa v1.0.2
   Compiling slog v2.7.0
   Compiling terminal_size v0.3.0
   Compiling nonempty v0.9.0
   Compiling radicle-dag v0.9.0
   Compiling normpath v1.3.0
   Compiling subplotlib-derive v0.11.0
   Compiling localtime v1.3.1
   Compiling radicle-surf v0.18.0
   Compiling fs2 v0.4.3
   Compiling crossbeam-channel v0.5.13
   Compiling siphasher v1.0.1
   Compiling nonempty v0.5.0
   Compiling fs_at v0.2.1
   Compiling state v0.5.3
   Compiling libyml v0.0.4
   Compiling remove_dir_all v0.8.3
   Compiling unescape v0.1.0
   Compiling base64 v0.13.1
   Compiling base64 v0.21.7
   Compiling arc-swap v1.7.1
   Compiling shell-words v1.1.0
   Compiling html-page v0.4.0
   Compiling uuid v1.10.0
   Compiling slog-scope v4.4.0
   Compiling radicle-git-ext v0.7.0
   Compiling serde_yml v0.0.11
   Compiling radicle-crypto v0.10.0
   Compiling slog-json v2.6.1
   Compiling radicle-cob v0.11.0
   Compiling radicle v0.12.0
   Compiling subplot-build v0.11.0
   Compiling subplotlib v0.11.0
   Compiling radicle-ci-broker v0.5.0 (/srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 16.36s
+ cargo doc --workspace
    Checking unicode-ident v1.0.12
 Documenting unicode-ident v1.0.12
 Documenting cfg-if v1.0.0
 Documenting typenum v1.17.0
 Documenting libc v0.2.158
 Documenting subtle v2.6.1
 Documenting bitflags v2.6.0
 Documenting cpufeatures v0.2.13
 Documenting tinyvec_macros v0.1.1
 Documenting opaque-debug v0.3.1
 Documenting memchr v2.7.4
 Documenting ed25519 v1.5.3
 Documenting ct-codecs v1.1.1
 Documenting log v0.4.22
 Documenting amplify_num v0.5.3
 Documenting percent-encoding v2.3.1
    Checking proc-macro2 v1.0.86
 Documenting ascii v1.1.0
 Documenting linux-raw-sys v0.4.14
 Documenting tinyvec v1.8.0
 Documenting proc-macro2 v1.0.86
 Documenting unicode-bidi v0.3.15
    Checking quote v1.0.37
    Checking git-ref-format-core v0.3.0
 Documenting keccak v0.1.5
    Checking syn v2.0.76
    Checking syn v1.0.109
 Documenting base64ct v1.6.0
 Documenting form_urlencoded v1.2.1
 Documenting byteorder v1.5.0
 Documenting itoa v1.0.11
 Documenting fastrand v2.1.1
 Documenting equivalent v1.0.1
    Checking data-encoding v2.6.0
 Documenting unicode-normalization v0.1.23
 Documenting hashbrown v0.14.5
 Documenting base32 v0.4.0
 Documenting quote v1.0.37
 Documenting sqlite3-src v0.5.1
 Documenting pem-rfc7468 v0.7.0
 Documenting data-encoding v2.6.0
 Documenting radicle-std-ext v0.1.0
 Documenting ryu v1.0.18
 Documenting rustix v0.38.34
    Checking amplify_syn v2.0.1
 Documenting getrandom v0.2.15
 Documenting libz-sys v1.1.20
    Checking proc-macro-error v1.0.4
 Documenting data-encoding-macro-internal v0.1.13
 Documenting once_cell v1.19.0
 Documenting zeroize v1.8.1
 Documenting sqlite3-sys v0.15.2
 Documenting signature v2.2.0
 Documenting powerfmt v0.2.0
 Documenting utf8parse v0.2.2
 Documenting base-x v0.2.11
 Documenting nix v0.29.0
 Documenting serde_derive v1.0.209
 Documenting amplify_syn v2.0.1
 Documenting thiserror-impl v1.0.63
 Documenting generic-array v0.14.7
 Documenting proc-macro-error-attr v1.0.4
 Documenting ec25519 v0.1.0
 Documenting idna v0.5.0
 Documenting libgit2-sys v0.16.2+1.7.2
 Documenting rand_core v0.6.4
 Documenting indexmap v2.4.0
 Documenting data-encoding-macro v0.1.15
 Documenting anstyle-parse v0.2.5
 Documenting sqlite v0.32.0
 Documenting deranged v0.3.11
 Documenting tempfile v3.12.0
 Documenting proc-macro-error v1.0.4
 Documenting crypto-common v0.1.6
 Documenting block-padding v0.3.3
 Documenting block-buffer v0.10.4
 Documenting rand v0.8.5
 Documenting amplify_derive v4.0.1
 Documenting thiserror v1.0.63
 Documenting multibase v0.9.1
 Documenting url v2.5.2
 Documenting aho-corasick v1.1.3
 Documenting time-macros v0.2.18
 Documenting cvt v0.1.2
 Documenting time-core v0.1.2
 Documenting inout v0.1.3
 Documenting num-conv v0.1.0
 Documenting colorchoice v1.0.2
 Documenting git-ref-format-macro v0.3.0
 Documenting serde v1.0.209
 Documenting regex-syntax v0.8.4
 Documenting digest v0.10.7
 Documenting universal-hash v0.5.1
 Documenting aead v0.5.2
 Documenting qcheck v1.0.0
 Documenting git2 v0.18.3
 Documenting amplify v4.7.0
 Documenting radicle-ssh v0.9.0
 Documenting anstyle-query v1.1.1
 Documenting cipher v0.4.4
 Documenting is_terminal_polyfill v1.70.1
 Documenting anstyle v1.0.8
 Documenting time v0.3.36
 Documenting fs_at v0.2.1
 Documenting terminal_size v0.3.0
 Documenting polyval v0.6.2
 Documenting poly1305 v0.8.0
 Documenting sha2 v0.10.8
 Documenting sha3 v0.10.8
 Documenting pbkdf2 v0.12.2
 Documenting culpa-macros v1.0.2
 Documenting anstream v0.6.15
 Documenting radicle-dag v0.9.0
 Documenting cyphergraphy v0.3.0
   Compiling radicle-ci-broker v0.5.0 (/srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src)
 Documenting slog v2.7.0
 Documenting regex-automata v0.4.7
 Documenting ctr v0.9.2
 Documenting aes v0.8.4
 Documenting ghash v0.5.1
 Documenting chacha20 v0.9.1
 Documenting ssh-encoding v0.2.0
 Documenting cbc v0.1.2
 Documenting blowfish v0.9.1
 Documenting crossbeam-utils v0.8.20
 Documenting lazy_static v1.5.0
 Documenting git-ref-format-core v0.3.0
 Documenting serde_json v1.0.127
 Documenting nonempty v0.9.0
 Documenting cypheraddr v0.4.0
 Documenting strsim v0.11.1
    Checking heck v0.5.0
 Documenting normpath v1.3.0
 Documenting utf8-width v0.1.7
 Documenting aes-gcm v0.10.3
 Documenting clap_lex v0.7.2
 Documenting heck v0.5.0
 Documenting localtime v1.3.1
 Documenting culpa v1.0.2
 Documenting bcrypt-pbkdf v0.10.0
 Documenting crossbeam-channel v0.5.13
 Documenting subplotlib-derive v0.11.0
 Documenting html-escape v0.2.13
 Documenting filetime v0.2.24
 Documenting git-ref-format v0.3.0
 Documenting remove_dir_all v0.8.3
 Documenting socks5-client v0.4.1
 Documenting clap_derive v4.5.13
 Documenting fs2 v0.4.3
 Documenting arc-swap v1.7.1
 Documenting ssh-cipher v0.2.0
 Documenting clap_builder v4.5.15
 Documenting libyml v0.0.4
 Documenting base64 v0.22.1
 Documenting unescape v0.1.0
 Documenting regex v1.10.6
 Documenting shell-words v1.1.0
 Documenting state v0.5.3
 Documenting siphasher v1.0.1
 Documenting html-page v0.4.0
 Documenting slog-json v2.6.1
 Documenting uuid v1.10.0
 Documenting radicle-git-ext v0.7.0
 Documenting cyphernet v0.5.2
 Documenting slog-scope v4.4.0
 Documenting ssh-key v0.6.6
 Documenting anyhow v1.0.86
 Documenting serde_yml v0.0.11
 Documenting subplotlib v0.11.0
 Documenting radicle-surf v0.18.0
 Documenting clap v4.5.16
 Documenting radicle-crypto v0.10.0
 Documenting radicle-cob v0.11.0
 Documenting radicle v0.12.0
 Documenting radicle-ci-broker v0.5.0 (/srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src)
    Finished `dev` profile [unoptimized + debuginfo] target(s) in 32.51s
   Generated /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/doc/radicle_ci_broker/index.html and 3 other files
+ cargo test --workspace --no-fail-fast
    Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
     Running unittests src/lib.rs (target/debug/deps/radicle_ci_broker-34380cd99ad6ece8)

running 40 tests
{"msg":"broker database: /tmp/.tmpuSvFem/db.db","level":"INFO","ts":"2024-08-29T10:16:40.776123687Z"}
{"msg":"broker database: /tmp/.tmpt5A8dr/db.db","level":"INFO","ts":"2024-08-29T10:16:40.776248388Z"}
{"msg":"broker database: /tmp/.tmpB8rT8V/db.db","level":"INFO","ts":"2024-08-29T10:16:40.776318788Z"}
{"msg":"broker database: /tmp/.tmpnWzCDS/db.db","level":"INFO","ts":"2024-08-29T10:16:40.776409858Z"}
test broker::test::does_not_have_a_default_adapter_initially ... ok
test broker::test::adds_adapter ... ok
{"msg":"broker database: /tmp/.tmpHlxFS2/db.db","level":"INFO","test broker::test::does_not_find_unknown_repo ... ok
ts":"2024-08-29T10:16:40.9251715Z"}
{"msg":"broker database: /tmp/.tmpPeDqwu/db.db","level":"INFO","ts":"2024-08-29T10:16:40.92532176Z"}
{"msg":"broker database: /tmp/.tmpceDPjX/db.db","level":"INFO","ts":"2024-08-29T10:16:40.925654141Z"}
test adapter::test::adapter_is_not_executable ... ok
{"msg":"broker database: /tmp/.tmpVlwf7I/db.db","level":"INFO","ts":"2024-08-29T10:16:40.957814452Z"}
test adapter::test::adapter_first_message_isnt_triggered ... ok
test event::test::branch_is_not_patch_update ... ok
test event::test::get_no_push_branch ... ok
test event::test::get_push_branch ... ok
test event::test::patch_branch_is_not_patch_update ... ok
test event::test::patch_update ... ok
{"msg":"start CI run","level":"INFO","ts":"2024-08-29T10:16:40.968930052Z"}
{"msg":"trigger event: Trigger {\n    common: EventCommonFields {\n        version: 1,\n        event_type: Push,\n        repository: Repository {\n            id: RepoId(rad:z35x66tQEygtLoctuSRCQR5TmGSkt),\n            name: \"acme\",\n            description: \"Acme's repository\",\n            private: false,\n            default_branch: \"master\",\n            delegates: [\n                Did(\"did:key:z6MknNvgQHP6qjQxQAmFrBeFrafigcvz2cTr8z6ogANWTpmA\"),\n            ],\n        },\n    },\n    push: Some(\n        PushEvent {\n            pusher: Author {\n                id: Did(\"did:key:z6MknNvgQHP6qjQxQAmFrBeFrafigcvz2cTr8z6ogANWTpmA\"),\n                alias: None,\n            },\n            before: Oid(\n                f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354,\n            ),\n            after: Oid(\n                532c92e4ec973d79c25652c581ae82d238e46a06,\n            ),\n            branch: \"master\",\n            commits: [\n                Oid(\n                    532c92e4ec973d79c25652c581ae82d238e46a06,\n                ),\n            ],\n        },\n    ),\n    patch: None,\n}","level":"DEBG","ts":"2024-08-29T10:16:40.969145163Z"}
test event::test::test_parse_invalid_ref ... ok
test event::test::test_parse_patch_ref ... ok
test event::test::test_parse_push_ref ... ok
test event::test_broker_event::name_for_branch ... ok
{"msg":"adapter was terminated by signal","level":"DEBG"test event::test_broker_event::name_for_shutdown ... ok
,"ts":"2024-08-29T10:16:40.975988566Z"}
{"msg":"adapter did not exit voluntarily","level":"WARN","ts":"2024-08-29T10:16:40.976031617Z"}
test adapter::test::adapter_is_killed_after_second_message ... ok
{"msg":"no first response message","level":"ERRO","ts":"2024-08-29T10:16:40.977362689Z"}
{"msg":"no second response message","level":"ERRO","ts":"2024-08-29T10:16:40.977410439Z"}
{"msg":"adapter was terminated by signal","level":"DEBG","ts":"2024-08-29T10:16:40.977454419Z"}
{"msg":"adapter did not exit voluntarily","level":"test event::test_broker_event::nid_for_ref_with_namespace ... WARNok"
,"ts":"2024-08-29T10:16:40.977495519Z"}
test adapter::test::adapter_is_killed_before_any_messages ... ok
test event::test_broker_event::nid_for_plain_branch_name ... ok
test event::test_broker_event::nid_for_ref_without_namespace ... ok
test adapter::test::adapter_has_bad_interpreter ... ok
{"msg":"adapter exit code","level":"DEBG","ts":"2024-08-29T10:16:40.979919153Z","exit_code":1}
{"msg":"adapter stderr","level":"DEBG","ts":"2024-08-29T10:16:40.979967144Z","stderr":"woe be me"}
test adapter::test::adapter_exits_nonzero ... ok
{"msg":"adapter exit code","level":"DEBG","ts":"2024-08-29T10:16:40.980655615Z","exit_code":0}
test event::test_parse_ref::namespaced_patch ... ok
test adapter::test::adapter_reports_success ... ok
test run::test::serialize_run_state ... ok
test event::test_parse_ref::namespaced_branch ... ok
test event::test_parse_ref::plain_branch_name_is_none ... ok
test event::test_parse_ref::namespaced_branch_with_slashes ... ok
test adapter::test::adapter_does_not_exist ... ok
{"msg":"adapter exit code","level":"DEBG","ts":"2024-08-29T10:16:40.98310989Z","exit_code":0}
test adapter::test::adapter_reports_failure ... ok
{"msg":"too many response messages","level":"ERRO","ts":"2024-08-29T10:16:40.987558038Z"}
test adapter::test::adapter_outputs_too_many_messages ... ok
test adapter::test::adapter_produces_as_bad_message ... ok
{"msg":"no second response message","level":"ERRO","ts":"2024-08-29T10:16:40.990633404Z"}
{"msg":"adapter was terminated by signal","level":"DEBG","ts":"2024-08-29T10:16:40.990668364Z"}
{"msg":"adapter did not exit voluntarily","level":"WARN","ts":"2024-08-29T10:16:40.990694434Z"}
test adapter::test::adapter_is_killed_after_first_message ... ok
test msg::tests::trigger_push ... ok
test msg::tests::trigger_patch ... ok
test broker::test::finds_default_adapter_for_unknown_repo ... ok
test broker::test::has_no_adapters_initially ... ok
test broker::test::sets_a_default_adapter_initially ... ok
{"msg":"adapter exit code","level":"DEBG","ts":"2024-08-29T10:16:41.132002342Z","exit_code":1}
{"msg":"adapter stderr","level":"DEBG","ts":"2024-08-29T10:16:41.132053012Z","stderr":"woe be me"}
{"msg":"start CI run","level":"INFO","ts":"2024-08-29T10:16:41.148611963Z"}
{"msg":"trigger event: Trigger {\n    common: EventCommonFields {\n        version: 1,\n        event_type: Push,\n        repository: Repository {\n            id: RepoId(rad:zQdRERjqZ1SpV4FeUrAKVMgKDQqk),\n            name: \"acme\",\n            description: \"Acme's repository\",\n            private: false,\n            default_branch: \"master\",\n            delegates: [\n                Did(\"did:key:z6MkiXYKroeE2Jv1e5TeUEH1bRu3pDFiarTbWxcE8K7h8GQF\"),\n            ],\n        },\n    },\n    push: Some(\n        PushEvent {\n            pusher: Author {\n                id: Did(\"did:key:z6MkiXYKroeE2Jv1e5TeUEH1bRu3pDFiarTbWxcE8K7h8GQF\"),\n                alias: None,\n            },\n            before: Oid(\n                f2de534b5e81d7c6e2dcaf58c3dd91573c0a0354,\n            ),\n            after: Oid(\n                532c92e4ec973d79c25652c581ae82d238e46a06,\n            ),\n            branch: \"master\",\n            commits: [\n                Oid(\n                    532c92e4ec973d79c25652c581ae82d238e46a06,\n                ),\n            ],\n        },\n    ),\n    patch: None,\n}","level":"DEBG","ts":"2024-08-29T10:16:41.148758114Z"}
{"msg":"failed to run adapter or it failed to run CI: child process failed with wait status 1","level":"ERRO","ts":"2024-08-29T10:16:41.170276904Z"}
{"msg":"Finish CI run","level":"INFO","ts":"2024-08-29T10:16:41.170310384Z"}
{"msg":"finished CI run: Run {\n    broker_run_id: RunId {\n        id: \"23fe3ba2-fa3b-49f5-859d-2caf51b366e9\",\n    },\n    adapter_run_id: Some(\n        RunId {\n            id: \"xyzzy\",\n        },\n    ),\n    adapter_info_url: None,\n    repo_id: RepoId(rad:z35x66tQEygtLoctuSRCQR5TmGSkt),\n    repo_name: \"acme\",\n    timestamp: \"2024-08-29 10:16:40Z\",\n    whence: Branch {\n        name: \"push-event-has-no-branch-name\",\n        commit: Oid(\n            532c92e4ec973d79c25652c581ae82d238e46a06,\n        ),\n        who: Some(\n            \"did:key:z6MknNvgQHP6qjQxQAmFrBeFrafigcvz2cTr8z6ogANWTpmA\",\n        ),\n    },\n    state: Finished,\n    result: Some(\n        Success,\n    ),\n}","level":"DEBG","ts":"2024-08-29T10:16:41.170425314Z"}
test broker::test::adapter_fails ... ok
{"msg":"adapter exit code","level":"DEBG","ts":"2024-08-29T10:16:41.231561771Z","exit_code":0}
{"msg":"Finish CI run","level":"INFO","ts":"2024-08-29T10:16:41.253936703Z"}
{"msg":"finished CI run: Run {\n    broker_run_id: RunId {\n        id: \"a564635f-f680-4685-bb43-60f8039b2768\",\n    },\n    adapter_run_id: Some(\n        RunId {\n            id: \"xyzzy\",\n        },\n    ),\n    adapter_info_url: None,\n    repo_id: RepoId(rad:zQdRERjqZ1SpV4FeUrAKVMgKDQqk),\n    repo_name: \"acme\",\n    timestamp: \"2024-08-29 10:16:41Z\",\n    whence: Branch {\n        name: \"push-event-has-no-branch-name\",\n        commit: Oid(\n            532c92e4ec973d79c25652c581ae82d238e46a06,\n        ),\n        who: Some(\n            \"did:key:z6MkiXYKroeE2Jv1e5TeUEH1bRu3pDFiarTbWxcE8K7h8GQF\",\n        ),\n    },\n    state: Finished,\n    result: Some(\n        Success,\n    ),\n}","level":"DEBG","ts":"2024-08-29T10:16:41.254057973Z"}
test broker::test::executes_adapter ... ok

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

     Running unittests src/bin/cib.rs (target/debug/deps/cib-2286d5fd9ecb98e1)

running 0 tests

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

     Running unittests src/bin/cibtool.rs (target/debug/deps/cibtool-43d6f7069a1c0cb1)

running 0 tests

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

     Running unittests src/bin/synthetic-events.rs (target/debug/deps/synthetic_events-bdc28d0a8fcf3cb0)

running 0 tests

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

     Running tests/subplot.rs (target/debug/deps/subplot-abcd01b55d95e39c)

running 32 tests
test adapter_with_url_runs_successfully ... ok
test dummy_adapter_runs_successfully ... ok
test gives_helpful_error_message_if_it_doesn_t_understand_its_configuration_file ... FAILED
test don_t_insert_events_into_queue_when_not_allowed_by_filter ... FAILED
test events_can_be_queued_and_removed_from_queue ... FAILED
test don_t_insert_event_for_non_existent_repository ... FAILED
test adapter_can_provide_url_for_info_on_run ... FAILED
test add_information_about_triggered_run_to_database ... FAILED
test add_information_about_run_that_s_running_to_database ... FAILED
test add_information_about_run_that_s_finished_in_failure_to_database ... FAILED
test can_trigger_a_ci_run ... FAILED
test add_information_about_run_that_s_finished_successfully_to_database ... FAILED
test gives_helpful_error_message_if_node_socket_can_t_be_found ... FAILED
test insert_events_into_queue ... FAILED
test insert_many_events_into_queue ... FAILED
test logs_adapter_stderr_output ... FAILED
test logs_start_and_successful_end ... FAILED
test produces_a_json_status_file ... FAILED
test reports_it_version ... FAILED
test produces_a_report_page_upon_request ... FAILED
test process_queued_events ... FAILED
test shuts_down_when_requested ... FAILED
test we_can_run_rad ... FAILED
test smoke_test__runs_adapter ... FAILED
test stops_if_the_node_connection_breaks ... FAILED
test update_and_show_information_about_run_to_running ... FAILED
test shows_config_as_json ... ok
test logs_termination_due_to_error ... FAILED
test can_add_shutdown_event_to_queue ... ok
test event_synthesizer_terminates_after_first_connection ... ok
test count_in_a_single_process has been running for over 60 seconds
test count_in_concurrent_processes has been running for over 60 seconds
test count_in_a_single_process ... ok
test count_in_concurrent_processes ... ok

failures:

---- gives_helpful_error_message_if_it_doesn_t_understand_its_configuration_file stdout ----
unknown: scenario: Gives helpful error message if it doesn't understand its configuration file
ci-broker.md:380:1:   step: given file radenv.sh
ci-broker.md:381:1:   step: given file setup-node.sh
ci-broker.md:382:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotgWN2HGives-helpful-error-message-if-it-doesn-t-understand-its-configuration-file
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotgWN2HGives-helpful-error-message-if-it-doesn-t-understand-its-configuration-file/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'gives_helpful_error_message_if_it_doesn_t_understand_its_configuration_file' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:1018:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- don_t_insert_events_into_queue_when_not_allowed_by_filter stdout ----
unknown: scenario: Don't insert events into queue when not allowed by filter
ci-broker.md:713:1:   step: given file radenv.sh
ci-broker.md:714:1:   step: given file setup-node.sh
ci-broker.md:715:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotnCBekDon-t-insert-events-into-queue-when-not-allowed-by-filter
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotnCBekDon-t-insert-events-into-queue-when-not-allowed-by-filter/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'don_t_insert_events_into_queue_when_not_allowed_by_filter' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:2415:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace

---- events_can_be_queued_and_removed_from_queue stdout ----
unknown: scenario: Events can be queued and removed from queue
ci-broker.md:875:1:   step: given file radenv.sh
ci-broker.md:876:1:   step: given file setup-node.sh
ci-broker.md:877:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplot35pfzEvents-can-be-queued-and-removed-from-queue
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplot35pfzEvents-can-be-queued-and-removed-from-queue/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'events_can_be_queued_and_removed_from_queue' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:2901:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- don_t_insert_event_for_non_existent_repository stdout ----
unknown: scenario: Don't insert event for non-existent repository
ci-broker.md:1100:1:   step: given file radenv.sh
ci-broker.md:1101:1:   step: given file setup-node.sh
ci-broker.md:1102:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotHh0B4Don-t-insert-event-for-non-existent-repository
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotHh0B4Don-t-insert-event-for-non-existent-repository/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'don_t_insert_event_for_non_existent_repository' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:4183:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- adapter_can_provide_url_for_info_on_run stdout ----
unknown: scenario: Adapter can provide URL for info on run
ci-broker.md:312:1:   step: given file radenv.sh
ci-broker.md:313:1:   step: given file setup-node.sh
ci-broker.md:314:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotSWDqpAdapter-can-provide-URL-for-info-on-run
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotSWDqpAdapter-can-provide-URL-for-info-on-run/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'adapter_can_provide_url_for_info_on_run' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:783:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- add_information_about_triggered_run_to_database stdout ----
unknown: scenario: Add information about triggered run to database
ci-broker.md:950:1:   step: given file radenv.sh
ci-broker.md:951:1:   step: given file setup-node.sh
ci-broker.md:952:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotUJ4hEAdd-information-about-triggered-run-to-database
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotUJ4hEAdd-information-about-triggered-run-to-database/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'add_information_about_triggered_run_to_database' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:3223:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- add_information_about_run_that_s_running_to_database stdout ----
unknown: scenario: Add information about run that's running to database
ci-broker.md:973:1:   step: given file radenv.sh
ci-broker.md:974:1:   step: given file setup-node.sh
ci-broker.md:975:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotmurf2Add-information-about-run-that-s-running-to-database
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotmurf2Add-information-about-run-that-s-running-to-database/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'add_information_about_run_that_s_running_to_database' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:3357:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- add_information_about_run_that_s_finished_in_failure_to_database stdout ----
unknown: scenario: Add information about run that's finished in failure to database
ci-broker.md:1023:1:   step: given file radenv.sh
ci-broker.md:1024:1:   step: given file setup-node.sh
ci-broker.md:1025:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotYOGiXAdd-information-about-run-that-s-finished-in-failure-to-database
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotYOGiXAdd-information-about-run-that-s-finished-in-failure-to-database/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'add_information_about_run_that_s_finished_in_failure_to_database' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:3666:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- can_trigger_a_ci_run stdout ----
unknown: scenario: Can trigger a CI run
ci-broker.md:928:1:   step: given file radenv.sh
ci-broker.md:929:1:   step: given file setup-node.sh
ci-broker.md:930:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotsBr5fCan-trigger-a-CI-run
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotsBr5fCan-trigger-a-CI-run/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'can_trigger_a_ci_run' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:3089:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- add_information_about_run_that_s_finished_successfully_to_database stdout ----
unknown: scenario: Add information about run that's finished successfully to database
ci-broker.md:998:1:   step: given file radenv.sh
ci-broker.md:999:1:   step: given file setup-node.sh
ci-broker.md:1000:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotIGk44Add-information-about-run-that-s-finished-successfully-to-database
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotIGk44Add-information-about-run-that-s-finished-successfully-to-database/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'add_information_about_run_that_s_finished_successfully_to_database' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:3500:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- gives_helpful_error_message_if_node_socket_can_t_be_found stdout ----
unknown: scenario: Gives helpful error message if node socket can't be found
ci-broker.md:352:1:   step: given file radenv.sh
ci-broker.md:353:1:   step: given file setup-node.sh
ci-broker.md:354:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotCfJe3Gives-helpful-error-message-if-node-socket-can-t-be-found
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotCfJe3Gives-helpful-error-message-if-node-socket-can-t-be-found/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'gives_helpful_error_message_if_node_socket_can_t_be_found' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:892:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- insert_events_into_queue stdout ----
unknown: scenario: Insert events into queue
ci-broker.md:654:1:   step: given file radenv.sh
ci-broker.md:655:1:   step: given file setup-node.sh
ci-broker.md:656:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotrGPKKInsert-events-into-queue
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotrGPKKInsert-events-into-queue/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'insert_events_into_queue' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:2095:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- insert_many_events_into_queue stdout ----
unknown: scenario: Insert many events into queue
ci-broker.md:683:1:   step: given file radenv.sh
ci-broker.md:684:1:   step: given file setup-node.sh
ci-broker.md:685:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotIu2kTInsert-many-events-into-queue
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotIu2kTInsert-many-events-into-queue/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'insert_many_events_into_queue' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:2253:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- logs_adapter_stderr_output stdout ----
unknown: scenario: Logs adapter stderr output
ci-broker.md:493:1:   step: given file radenv.sh
ci-broker.md:494:1:   step: given file setup-node.sh
ci-broker.md:495:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotput8MLogs-adapter-stderr-output
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotput8MLogs-adapter-stderr-output/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'logs_adapter_stderr_output' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:1593:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- logs_start_and_successful_end stdout ----
unknown: scenario: Logs start and successful end
ci-broker.md:1163:1:   step: given file radenv.sh
ci-broker.md:1164:1:   step: given file setup-node.sh
ci-broker.md:1165:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotfAad4Logs-start-and-successful-end
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotfAad4Logs-start-and-successful-end/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'logs_start_and_successful_end' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:4340:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- produces_a_json_status_file stdout ----
unknown: scenario: Produces a JSON status file
ci-broker.md:1220:1:   step: given file radenv.sh
ci-broker.md:1221:1:   step: given file setup-node.sh
ci-broker.md:1222:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotSpV28Produces-a-JSON-status-file
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotSpV28Produces-a-JSON-status-file/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'produces_a_json_status_file' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:4541:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- reports_it_version stdout ----
unknown: scenario: Reports it version
ci-broker.md:282:1:   step: given file radenv.sh
ci-broker.md:283:1:   step: given file setup-node.sh
ci-broker.md:284:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotJTLesReports-it-version
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotJTLesReports-it-version/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'reports_it_version' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:574:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- produces_a_report_page_upon_request stdout ----
unknown: scenario: Produces a report page upon request
ci-broker.md:465:1:   step: given file radenv.sh
ci-broker.md:466:1:   step: given file setup-node.sh
ci-broker.md:467:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotsiaqmProduces-a-report-page-upon-request
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotsiaqmProduces-a-report-page-upon-request/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'produces_a_report_page_upon_request' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:1413:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- process_queued_events stdout ----
unknown: scenario: Process queued events
ci-broker.md:747:1:   step: given file radenv.sh
ci-broker.md:748:1:   step: given file setup-node.sh
ci-broker.md:749:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplot2byekProcess-queued-events
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplot2byekProcess-queued-events/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'process_queued_events' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:2664:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- shuts_down_when_requested stdout ----
unknown: scenario: Shuts down when requested
ci-broker.md:439:1:   step: given file radenv.sh
ci-broker.md:440:1:   step: given file setup-node.sh
ci-broker.md:441:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplot8hmzLShuts-down-when-requested
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplot8hmzLShuts-down-when-requested/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'shuts_down_when_requested' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:1291:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- we_can_run_rad stdout ----
unknown: scenario: We can run rad
ci-broker.md:533:1:   step: given file radenv.sh
ci-broker.md:534:1:   step: when I run bash radenv.sh rad --version
Running `bash` with args ["radenv.sh", "rad", "--version"]
Running in /tmp/subplotSguY3We-can-run-rad
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
env: ‘rad’: No such file or directory


  return: Failure
thread 'we_can_run_rad' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:1641:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- smoke_test__runs_adapter stdout ----
unknown: scenario: Smoke test: Runs adapter
ci-broker.md:242:1:   step: given file radenv.sh
ci-broker.md:243:1:   step: given file setup-node.sh
ci-broker.md:244:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotJUeI2Smoke-test--Runs-adapter
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotJUeI2Smoke-test--Runs-adapter/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'smoke_test__runs_adapter' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:459:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- stops_if_the_node_connection_breaks stdout ----
unknown: scenario: Stops if the node connection breaks
ci-broker.md:410:1:   step: given file radenv.sh
ci-broker.md:411:1:   step: given file setup-node.sh
ci-broker.md:412:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotm449YStops-if-the-node-connection-breaks
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotm449YStops-if-the-node-connection-breaks/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'stops_if_the_node_connection_breaks' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:1136:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- update_and_show_information_about_run_to_running stdout ----
unknown: scenario: Update and show information about run to running
ci-broker.md:1050:1:   step: given file radenv.sh
ci-broker.md:1051:1:   step: given file setup-node.sh
ci-broker.md:1052:1:   step: when I run bash radenv.sh bash setup-node.sh
Running `bash` with args ["radenv.sh", "bash", "setup-node.sh"]
Running in /tmp/subplotcigfUUpdate-and-show-information-about-run-to-running
ENV: SHELL = /bin/sh
PATH: /home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 127
Stdout:

Stderr:
+ mkdir -p /tmp/subplotcigfUUpdate-and-show-information-about-run-to-running/homedir
+ rad auth --alias brokertest
setup-node.sh: line 7: rad: command not found


  return: Failure
thread 'update_and_show_information_about_run_to_running' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:4010:20:
called `Result::unwrap()` on an `Err` value: "expected exit code 0, but had Some(127)"

---- logs_termination_due_to_error stdout ----
unknown: scenario: Logs termination due to error
ci-broker.md:1194:1:   step: given an installed cib
ci-broker.md:1196:1:   step: given file broker.yaml
ci-broker.md:1197:1:   step: when I try to run cib --config broker.yaml queued
Running `cib` with args ["--config", "broker.yaml", "queued"]
Running in /tmp/subplotA2WVvLogs-termination-due-to-error
ENV: SHELL = /bin/sh
PATH: /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug:/home/_rad/.cargo/bin:/bin:/usr/bin:/sbin:/usr/sbin
Exit code: 101
Stdout:

Stderr:
{"msg":"CI broker starts","level":"INFO","ts":"2024-08-29T10:16:41.27871501Z"}
{"msg":"loaded configuration Config {\n    default_adapter: \"mcadapterface\",\n    adapters: {\n        \"mcadapterface\": Adapter { \n         command: \"./adapter.sh\", \n         env: {\n            \"RADICLE_NATIVE_CI\": \"native-ci.yaml\",\n        }, \n         sensitive_env: {} },\n    },\n    filters: [\n        Branch(\n            \"main\",\n        ),\n    ],\n    report_dir: Some(\n        \"reports\",\n    ),\n    status_update_interval_seconds: None,\n    db: \"ci-broker.db\",\n}","level":"DEBG","ts":"2024-08-29T10:16:41.279729082Z"}
{"msg":"broker database: ci-broker.db","level":"INFO","ts":"2024-08-29T10:16:41.283685279Z"}
{"msg":"default adapter: Adapter { bin: \"./adapter.sh\", env: {\"RADICLE_NATIVE_CI\": \"native-ci.yaml\"} }","level":"DEBG","ts":"2024-08-29T10:16:41.526312509Z"}
{"msg":"start thread to process events until a shutdown event","level":"INFO","ts":"2024-08-29T10:16:41.529604455Z"}
{"msg":"queue in db has 0 events","level":"DEBG","ts":"2024-08-29T10:16:41.529754996Z"}
{"msg":"queue in db has 0 events","level":"DEBG","ts":"2024-08-29T10:16:41.529820896Z"}
{"msg":"event notification channel disconnected","level":"INFO","ts":"2024-08-29T10:16:41.529852306Z"}
{"msg":"start thread to process events ends","level":"INFO","ts":"2024-08-29T10:16:41.529879636Z"}
{"msg":"broker database has 0 CI runs","level":"DEBG","ts":"2024-08-29T10:16:41.530181256Z"}
{"msg":"start page updater thread","level":"INFO","ts":"2024-08-29T10:16:41.530212016Z"}
{"msg":"wait about 60 seconds to update HTML report pages again","level":"INFO","ts":"2024-08-29T10:16:41.530236066Z"}
thread 'main' panicked at src/bin/cib.rs:180:14:
page updater thread succeeded: Write("reports/index.html", Os { code: 2, kind: NotFound, message: "No such file or directory" })
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace


ci-broker.md:1199:1:   step: then stderr contains "CI broker starts"
ci-broker.md:1200:1:   step: then stderr contains "CI broker ends in unrecoverable error"
  return: Failure
thread 'logs_termination_due_to_error' panicked at /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src/target/debug/build/radicle-ci-broker-98abe7c6d9689d74/out/ci-broker.rs:4406:20:
called `Result::unwrap()` on an `Err` value: "stderr does not contain \"CI broker ends in unrecoverable error\""


failures:
    adapter_can_provide_url_for_info_on_run
    add_information_about_run_that_s_finished_in_failure_to_database
    add_information_about_run_that_s_finished_successfully_to_database
    add_information_about_run_that_s_running_to_database
    add_information_about_triggered_run_to_database
    can_trigger_a_ci_run
    don_t_insert_event_for_non_existent_repository
    don_t_insert_events_into_queue_when_not_allowed_by_filter
    events_can_be_queued_and_removed_from_queue
    gives_helpful_error_message_if_it_doesn_t_understand_its_configuration_file
    gives_helpful_error_message_if_node_socket_can_t_be_found
    insert_events_into_queue
    insert_many_events_into_queue
    logs_adapter_stderr_output
    logs_start_and_successful_end
    logs_termination_due_to_error
    process_queued_events
    produces_a_json_status_file
    produces_a_report_page_upon_request
    reports_it_version
    shuts_down_when_requested
    smoke_test__runs_adapter
    stops_if_the_node_connection_breaks
    update_and_show_information_about_run_to_running
    we_can_run_rad

test result: FAILED. 7 passed; 25 failed; 0 ignored; 0 measured; 0 filtered out; finished in 74.16s

error: test failed, to rerun pass `--test subplot`
   Doc-tests radicle_ci_broker

running 2 tests
test src/event.rs - event (line 11) ... ok
test src/msg.rs - msg::RunId (line 44) ... ok

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

error: 1 target failed:
    `--test subplot`

Log from Radicle native CI

Request message

`.radicle/native.yaml`

Table of contents

Run: `git clone /home/_rad/.radicle/storage/zwTxygwuz5LDGBq255RA2CbNGrz8 /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src`

Run: `git config advice.detachedHead false`

Run: `git checkout e82503bf951280285bf5f7decb5af9968bccfce2`

Run: `git show e82503bf951280285bf5f7decb5af9968bccfce2`

Log from Radicle native CI

Request message

.radicle/native.yaml

Table of contents

Run: git clone /home/_rad/.radicle/storage/zwTxygwuz5LDGBq255RA2CbNGrz8 /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src

Run: git config advice.detachedHead false

Run: git checkout e82503bf951280285bf5f7decb5af9968bccfce2

Run: git show e82503bf951280285bf5f7decb5af9968bccfce2

`.radicle/native.yaml`

Run: `git clone /home/_rad/.radicle/storage/zwTxygwuz5LDGBq255RA2CbNGrz8 /srv/http/97e74d7e-233a-4b43-959f-ce59e29d6650/src`

Run: `git config advice.detachedHead false`

Run: `git checkout e82503bf951280285bf5f7decb5af9968bccfce2`

Run: `git show e82503bf951280285bf5f7decb5af9968bccfce2`