syncthing/lib/events/events_test.go

316 lines
6.3 KiB
Go
Raw Normal View History

2014-11-16 20:13:20 +00:00
// Copyright (C) 2014 The Syncthing Authors.
2014-09-29 19:43:32 +00:00
//
2015-03-07 20:36:35 +00:00
// This Source Code Form is subject to the terms of the Mozilla Public
// License, v. 2.0. If a copy of the MPL was not distributed with this file,
// You can obtain one at http://mozilla.org/MPL/2.0/.
2014-07-25 12:50:14 +00:00
package events
2014-07-13 19:07:24 +00:00
import (
"fmt"
"testing"
"time"
)
const timeout = 100 * time.Millisecond
2014-07-13 19:07:24 +00:00
func init() {
runningTests = true
}
2014-07-13 19:07:24 +00:00
func TestNewLogger(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
if l == nil {
t.Fatal("Unexpected nil Logger")
}
}
func TestSubscriber(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
s := l.Subscribe(0)
defer l.Unsubscribe(s)
2014-07-13 19:07:24 +00:00
if s == nil {
t.Fatal("Unexpected nil Subscription")
}
}
func TestTimeout(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
s := l.Subscribe(0)
defer l.Unsubscribe(s)
2014-07-13 19:07:24 +00:00
_, err := s.Poll(timeout)
if err != ErrTimeout {
2014-07-13 19:07:24 +00:00
t.Fatal("Unexpected non-Timeout error:", err)
}
}
func TestEventBeforeSubscribe(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
l.Log(DeviceConnected, "foo")
2014-07-13 19:07:24 +00:00
s := l.Subscribe(0)
defer l.Unsubscribe(s)
2014-07-13 19:07:24 +00:00
_, err := s.Poll(timeout)
if err != ErrTimeout {
2014-07-13 19:07:24 +00:00
t.Fatal("Unexpected non-Timeout error:", err)
}
}
func TestEventAfterSubscribe(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
s := l.Subscribe(AllEvents)
defer l.Unsubscribe(s)
l.Log(DeviceConnected, "foo")
2014-07-13 19:07:24 +00:00
ev, err := s.Poll(timeout)
if err != nil {
t.Fatal("Unexpected error:", err)
}
if ev.Type != DeviceConnected {
2014-07-13 19:07:24 +00:00
t.Error("Incorrect event type", ev.Type)
}
switch v := ev.Data.(type) {
case string:
if v != "foo" {
t.Error("Incorrect Data string", v)
}
default:
t.Errorf("Incorrect Data type %#v", v)
}
}
func TestEventAfterSubscribeIgnoreMask(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
s := l.Subscribe(DeviceDisconnected)
defer l.Unsubscribe(s)
l.Log(DeviceConnected, "foo")
2014-07-13 19:07:24 +00:00
_, err := s.Poll(timeout)
if err != ErrTimeout {
2014-07-13 19:07:24 +00:00
t.Fatal("Unexpected non-Timeout error:", err)
}
}
func TestBufferOverflow(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
s := l.Subscribe(AllEvents)
defer l.Unsubscribe(s)
2014-07-13 19:07:24 +00:00
t0 := time.Now()
for i := 0; i < BufferSize*2; i++ {
l.Log(DeviceConnected, "foo")
2014-07-13 19:07:24 +00:00
}
if time.Since(t0) > timeout {
t.Fatalf("Logging took too long")
}
}
func TestUnsubscribe(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
s := l.Subscribe(AllEvents)
l.Log(DeviceConnected, "foo")
2014-07-13 19:07:24 +00:00
_, err := s.Poll(timeout)
if err != nil {
t.Fatal("Unexpected error:", err)
}
l.Unsubscribe(s)
l.Log(DeviceConnected, "foo")
2014-07-13 19:07:24 +00:00
_, err = s.Poll(timeout)
if err != ErrClosed {
2014-07-13 19:07:24 +00:00
t.Fatal("Unexpected non-Closed error:", err)
}
}
func TestGlobalIDs(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
s := l.Subscribe(AllEvents)
defer l.Unsubscribe(s)
l.Log(DeviceConnected, "foo")
_ = l.Subscribe(AllEvents)
l.Log(DeviceConnected, "bar")
2014-07-13 19:07:24 +00:00
ev, err := s.Poll(timeout)
if err != nil {
t.Fatal("Unexpected error:", err)
}
if ev.Data.(string) != "foo" {
t.Fatal("Incorrect event:", ev)
}
id := ev.GlobalID
2014-07-13 19:07:24 +00:00
ev, err = s.Poll(timeout)
if err != nil {
t.Fatal("Unexpected error:", err)
}
if ev.Data.(string) != "bar" {
t.Fatal("Incorrect event:", ev)
}
if ev.GlobalID != id+1 {
t.Fatalf("ID not incremented (%d != %d)", ev.GlobalID, id+1)
}
}
func TestSubscriptionIDs(t *testing.T) {
l := NewLogger()
s := l.Subscribe(DeviceConnected)
defer l.Unsubscribe(s)
l.Log(DeviceDisconnected, "a")
l.Log(DeviceConnected, "b")
l.Log(DeviceConnected, "c")
l.Log(DeviceDisconnected, "d")
ev, err := s.Poll(timeout)
if err != nil {
t.Fatal("Unexpected error:", err)
}
if ev.GlobalID != 2 {
t.Fatal("Incorrect GlobalID:", ev.GlobalID)
}
if ev.SubscriptionID != 1 {
t.Fatal("Incorrect SubscriptionID:", ev.SubscriptionID)
}
ev, err = s.Poll(timeout)
if err != nil {
t.Fatal("Unexpected error:", err)
}
if ev.GlobalID != 3 {
t.Fatal("Incorrect GlobalID:", ev.GlobalID)
}
if ev.SubscriptionID != 2 {
t.Fatal("Incorrect SubscriptionID:", ev.SubscriptionID)
}
ev, err = s.Poll(timeout)
if err != ErrTimeout {
t.Fatal("Unexpected error:", err)
2014-07-13 19:07:24 +00:00
}
}
func TestBufferedSub(t *testing.T) {
l := NewLogger()
2014-07-13 19:07:24 +00:00
s := l.Subscribe(AllEvents)
defer l.Unsubscribe(s)
bs := NewBufferedSubscription(s, 10*BufferSize)
2014-07-13 19:07:24 +00:00
go func() {
for i := 0; i < 10*BufferSize; i++ {
l.Log(DeviceConnected, fmt.Sprintf("event-%d", i))
2014-07-13 19:07:24 +00:00
if i%30 == 0 {
// Give the buffer routine time to pick up the events
time.Sleep(20 * time.Millisecond)
}
}
}()
recv := 0
for recv < 10*BufferSize {
2014-07-13 19:07:24 +00:00
evs := bs.Since(recv, nil)
for _, ev := range evs {
if ev.GlobalID != recv+1 {
t.Fatalf("Incorrect ID; %d != %d", ev.GlobalID, recv+1)
2014-07-13 19:07:24 +00:00
}
recv = ev.GlobalID
2014-07-13 19:07:24 +00:00
}
}
}
func BenchmarkBufferedSub(b *testing.B) {
l := NewLogger()
s := l.Subscribe(AllEvents)
defer l.Unsubscribe(s)
bufferSize := BufferSize
bs := NewBufferedSubscription(s, bufferSize)
// The coord channel paces the sender according to the receiver,
// ensuring that no events are dropped. The benchmark measures sending +
// receiving + synchronization overhead.
coord := make(chan struct{}, bufferSize)
for i := 0; i < bufferSize-1; i++ {
coord <- struct{}{}
}
// Receive the events
done := make(chan error)
go func() {
recv := 0
var evs []Event
for i := 0; i < b.N; {
evs = bs.Since(recv, evs[:0])
for _, ev := range evs {
if ev.GlobalID != recv+1 {
done <- fmt.Errorf("skipped event %v %v", ev.GlobalID, recv)
return
}
recv = ev.GlobalID
coord <- struct{}{}
}
i += len(evs)
}
done <- nil
}()
// Send the events
eventData := map[string]string{
"foo": "bar",
"other": "data",
"and": "something else",
}
for i := 0; i < b.N; i++ {
l.Log(DeviceConnected, eventData)
<-coord
}
2014-07-13 19:07:24 +00:00
if err := <-done; err != nil {
b.Error(err)
}
b.ReportAllocs()
2014-07-13 19:07:24 +00:00
}
func TestSinceUsesSubscriptionId(t *testing.T) {
l := NewLogger()
s := l.Subscribe(DeviceConnected)
defer l.Unsubscribe(s)
bs := NewBufferedSubscription(s, 10*BufferSize)
l.Log(DeviceConnected, "a") // SubscriptionID = 1
l.Log(DeviceDisconnected, "b")
l.Log(DeviceDisconnected, "c")
l.Log(DeviceConnected, "d") // SubscriptionID = 2
// We need to loop for the events, as they may not all have been
// delivered to the buffered subscription when we get here.
t0 := time.Now()
for time.Since(t0) < time.Second {
events := bs.Since(0, nil)
if len(events) == 2 {
break
}
if len(events) > 2 {
t.Fatal("Incorrect number of events:", len(events))
}
}
events := bs.Since(1, nil)
if len(events) != 1 {
t.Fatal("Incorrect number of events:", len(events))
}
}