//! Tracing layer that broadcasts log events to the web gateway via SSE. //! //! ```text //! tracing::info!("...") //! │ //! ▼ //! WebLogLayer::on_event() //! │ //! ▼ //! LogBroadcaster::send() //! │ //! ├──► broadcast::Sender (live subscribers) //! └──► ring buffer (recent history for late joiners) //! │ //! ▼ //! SSE /api/logs/events //! ``` use std::collections::VecDeque; use std::sync::{Arc, Mutex}; use serde::Serialize; use tokio::sync::broadcast; use tracing::field::{Field, Visit}; use tracing_subscriber::Layer; use crate::safety::LeakDetector; /// Maximum number of recent log entries kept for late-joining SSE subscribers. const HISTORY_CAP: usize = 500; /// A single log entry broadcast to connected clients. #[derive(Debug, Clone, Serialize)] pub struct LogEntry { pub level: String, pub target: String, pub message: String, pub timestamp: String, } /// Broadcasts log entries to SSE subscribers. /// /// Created early in main.rs (before tracing init), shared with both /// the tracing layer and the gateway's SSE endpoint. /// /// Keeps a ring buffer of recent entries so browsers that connect /// after startup still see the boot log. pub struct LogBroadcaster { tx: broadcast::Sender, recent: Mutex>, /// Scrubs secrets from log messages before broadcasting to SSE clients. leak_detector: LeakDetector, } impl LogBroadcaster { pub fn new() -> Self { let (tx, _) = broadcast::channel(512); Self { tx, recent: Mutex::new(VecDeque::with_capacity(HISTORY_CAP)), leak_detector: LeakDetector::new(), } } pub fn send(&self, mut entry: LogEntry) { // Scrub secrets from the message before it reaches any subscriber. // This is defense-in-depth: even if code elsewhere accidentally logs // a secret, it won't be broadcast to SSE clients. entry.message = self .leak_detector .scan_and_clean(&entry.message) .unwrap_or_else(|_| "[log message redacted: contained blocked secret]".to_string()); // Stash in ring buffer (for late joiners) if let Ok(mut buf) = self.recent.lock() { if buf.len() >= HISTORY_CAP { buf.pop_front(); } buf.push_back(entry.clone()); } // Broadcast to live subscribers (ok to drop if nobody listening) let _ = self.tx.send(entry); } /// Subscribe to the live event stream. pub fn subscribe(&self) -> broadcast::Receiver { self.tx.subscribe() } /// Snapshot of recent entries for replaying to a new subscriber. pub fn recent_entries(&self) -> Vec { self.recent .lock() .map(|buf| buf.iter().cloned().collect()) .unwrap_or_default() } } impl Default for LogBroadcaster { fn default() -> Self { Self::new() } } /// Visitor that extracts the `message` field and all extra key-value /// fields from a tracing event. /// /// The terminal formatter shows something like: /// INFO ironclaw::agent: Request completed url="http://..." status=200 /// /// We replicate that by capturing both the message and the extra fields. struct MessageVisitor { message: String, fields: Vec, } impl MessageVisitor { fn new() -> Self { Self { message: String::new(), fields: Vec::new(), } } /// Build the final message string: "message key=val key=val ..." fn finish(self) -> String { if self.fields.is_empty() { self.message } else { format!("{} {}", self.message, self.fields.join(" ")) } } } impl Visit for MessageVisitor { fn record_debug(&mut self, field: &Field, value: &dyn std::fmt::Debug) { if field.name() == "message" { self.message = format!("{:?}", value); // Strip surrounding quotes from Debug output if self.message.starts_with('"') && self.message.ends_with('"') { self.message = self.message[1..self.message.len() - 1].to_string(); } } else { self.fields.push(format!("{}={:?}", field.name(), value)); } } fn record_str(&mut self, field: &Field, value: &str) { if field.name() == "message" { self.message = value.to_string(); } else { self.fields.push(format!("{}={}", field.name(), value)); } } } /// Tracing layer that forwards events to a [`LogBroadcaster`]. /// /// Only forwards DEBUG and above. Attach to the tracing subscriber /// alongside the existing fmt layer. /// /// Log messages are scrubbed through `LeakDetector` in `LogBroadcaster::send()` /// (the single funnel point for all log output, including late-joiner history). pub struct WebLogLayer { broadcaster: Arc, } impl WebLogLayer { pub fn new(broadcaster: Arc) -> Self { Self { broadcaster } } } impl Layer for WebLogLayer { fn on_event( &self, event: &tracing::Event<'_>, _ctx: tracing_subscriber::layer::Context<'_, S>, ) { let metadata = event.metadata(); // Only forward DEBUG+ if *metadata.level() > tracing::Level::DEBUG { return; } let mut visitor = MessageVisitor::new(); event.record(&mut visitor); let entry = LogEntry { level: metadata.level().to_string().to_uppercase(), target: metadata.target().to_string(), message: visitor.finish(), timestamp: chrono::Utc::now().to_rfc3339_opts(chrono::SecondsFormat::Millis, true), }; // LeakDetector scrubbing happens inside broadcaster.send() self.broadcaster.send(entry); } } #[cfg(test)] mod tests { use super::*; #[test] fn test_log_broadcaster_creation() { let broadcaster = LogBroadcaster::new(); // Should not panic with no receivers broadcaster.send(LogEntry { level: "INFO".to_string(), target: "test".to_string(), message: "hello".to_string(), timestamp: "2024-01-01T00:00:00.000Z".to_string(), }); } #[test] fn test_log_broadcaster_subscribe() { let broadcaster = LogBroadcaster::new(); let mut rx = broadcaster.subscribe(); broadcaster.send(LogEntry { level: "WARN".to_string(), target: "ironclaw::test".to_string(), message: "test warning".to_string(), timestamp: "2024-01-01T00:00:00.000Z".to_string(), }); let entry = rx.try_recv().expect("should receive entry"); assert_eq!(entry.level, "WARN"); assert_eq!(entry.message, "test warning"); } #[test] fn test_log_entry_serialization() { let entry = LogEntry { level: "ERROR".to_string(), target: "ironclaw::agent".to_string(), message: "something broke".to_string(), timestamp: "2024-01-01T00:00:00.000Z".to_string(), }; let json = serde_json::to_string(&entry).expect("should serialize"); assert!(json.contains("\"level\":\"ERROR\"")); assert!(json.contains("something broke")); } #[test] fn test_recent_entries_buffer() { let broadcaster = LogBroadcaster::new(); for i in 0..5 { broadcaster.send(LogEntry { level: "INFO".to_string(), target: "test".to_string(), message: format!("msg {}", i), timestamp: "2024-01-01T00:00:00.000Z".to_string(), }); } let recent = broadcaster.recent_entries(); assert_eq!(recent.len(), 5); assert_eq!(recent[0].message, "msg 0"); assert_eq!(recent[4].message, "msg 4"); } #[test] fn test_recent_entries_cap() { let broadcaster = LogBroadcaster::new(); // Overflow the buffer for i in 0..(HISTORY_CAP + 50) { broadcaster.send(LogEntry { level: "INFO".to_string(), target: "test".to_string(), message: format!("msg {}", i), timestamp: "2024-01-01T00:00:00.000Z".to_string(), }); } let recent = broadcaster.recent_entries(); assert_eq!(recent.len(), HISTORY_CAP); // Oldest should be msg 50 (first 50 evicted) assert_eq!(recent[0].message, "msg 50"); } #[test] fn test_recent_entries_available_without_subscribers() { let broadcaster = LogBroadcaster::new(); // No subscribe() call, just send broadcaster.send(LogEntry { level: "INFO".to_string(), target: "test".to_string(), message: "before anyone listened".to_string(), timestamp: "2024-01-01T00:00:00.000Z".to_string(), }); let recent = broadcaster.recent_entries(); assert_eq!(recent.len(), 1); assert_eq!(recent[0].message, "before anyone listened"); } #[test] fn test_message_visitor_finish_message_only() { let v = MessageVisitor { message: "hello world".to_string(), fields: vec![], }; assert_eq!(v.finish(), "hello world"); } #[test] fn test_message_visitor_finish_with_fields() { let v = MessageVisitor { message: "Request completed".to_string(), fields: vec![ "url=http://localhost:8080".to_string(), "status=200".to_string(), ], }; let result = v.finish(); assert_eq!( result, "Request completed url=http://localhost:8080 status=200" ); } #[test] fn test_message_visitor_finish_empty() { let v = MessageVisitor::new(); assert_eq!(v.finish(), ""); } #[test] fn test_broadcaster_has_leak_detector() { let broadcaster = LogBroadcaster::new(); // Verify the leak detector is initialized with default patterns assert!(broadcaster.leak_detector.pattern_count() > 0); } #[test] fn test_leak_detector_scrubs_api_key_in_log() { let detector = crate::safety::LeakDetector::new(); let msg = "Connecting with token sk-proj-test1234567890abcdefghij"; let result = detector.scan_and_clean(msg); // Should be blocked (OpenAI key pattern) assert!(result.is_err()); } #[test] fn test_leak_detector_passes_clean_log() { let detector = crate::safety::LeakDetector::new(); let msg = "Request completed status=200 url=https://api.example.com/data"; let result = detector.scan_and_clean(msg); assert!(result.is_ok()); assert_eq!(result.unwrap(), msg); } }