🕖 Add timestamp in Sinatra/Rack request logs

Author: timstott

lib/loga/event.rb

@@ -11,7 +11,7 @@ def initialize(opts = {})
     end
 
     def to_s
-      output = [message]
+      output = ["#{timestamp.iso8601(3)} #{message}"]
       if exception
         output.push exception.to_s
         output.push exception.backtrace.join("\n")

spec/integration/sinatra_spec.rb

@@ -72,14 +72,15 @@
     context 'get request' do
       it 'logs the request' do
         get '/ok', username: 'yoshi'
-        expect(last_log_entry).to eq("GET /ok?username=yoshi 200 in 0ms\n")
+        expect(last_log_entry)
+          .to eq("#{time_anchor.iso8601(3)} GET /ok?username=yoshi 200 in 0ms\n")
       end
     end
 
     context 'request with redirect' do
       it 'specifies the original path' do
         get '/new'
-        expect(last_log_entry).to eql("GET /new 302 in 0ms\n")
+        expect(last_log_entry).to eql("#{time_anchor.iso8601(3)} GET /new 302 in 0ms\n")
       end
     end
 

spec/unit/loga/event_spec.rb

@@ -1,6 +1,6 @@
 require 'spec_helper'
 
-RSpec.describe Loga::Event do
+RSpec.describe Loga::Event, timecop: true do
   describe 'initialize' do
     context 'no message is passed' do
       it 'sets message to an empty string' do
@@ -19,7 +19,7 @@
   end
 
   describe '#to_s' do
-    let(:opts) { { message: 'Hello World' } }
+    let(:opts) { { message: 'Hello World', timestamp: Time.now } }
     subject { described_class.new(opts) }
 
     context 'when exception' do
@@ -28,19 +28,20 @@
       end
       let(:opts) { super().merge(exception: exception) }
       it 'outputs the message with exception' do
-        expect(subject.to_s).to eql("Hello World\nSome Message\nfile")
+        expect(subject.to_s)
+          .to eql("#{time_anchor.iso8601(3)} Hello World\nSome Message\nfile")
       end
     end
 
     context 'when no exception' do
       it 'outputs the message' do
-        expect(subject.to_s).to eql('Hello World')
+        expect(subject.to_s).to eql("#{time_anchor.iso8601(3)} Hello World")
       end
     end
   end
 
   describe '#inspect' do
-    subject { described_class.new message: 'Hey Siri' }
+    subject { described_class.new message: 'Hey Siri', timestamp: Time.now }
 
     it 'aliases to to_s' do
       expect(subject.to_s).to eql(subject.inspect)