|
| 1 | +// +build unit |
| 2 | + |
| 3 | +package amqp_test |
| 4 | + |
| 5 | +import ( |
| 6 | + "context" |
| 7 | + "fmt" |
| 8 | + "io" |
| 9 | + "testing" |
| 10 | + "time" |
| 11 | + |
| 12 | + "github.com/hellofresh/goengine" |
| 13 | + "github.com/hellofresh/goengine/driver/sql" |
| 14 | + "github.com/hellofresh/goengine/extension/amqp" |
| 15 | + goengineLogger "github.com/hellofresh/goengine/extension/logrus" |
| 16 | + "github.com/sirupsen/logrus" |
| 17 | + "github.com/sirupsen/logrus/hooks/test" |
| 18 | + libamqp "github.com/streadway/amqp" |
| 19 | + "github.com/stretchr/testify/assert" |
| 20 | + "github.com/stretchr/testify/require" |
| 21 | +) |
| 22 | + |
| 23 | +func TestListener_Listen(t *testing.T) { |
| 24 | + t.Run("Listen, consume and stop", func(t *testing.T) { |
| 25 | + ensure := require.New(t) |
| 26 | + |
| 27 | + ctx, ctxCancel := context.WithTimeout(context.Background(), time.Second) |
| 28 | + defer ctxCancel() |
| 29 | + |
| 30 | + consumeCalls := 0 |
| 31 | + consume := func() (io.Closer, <-chan libamqp.Delivery, error) { |
| 32 | + consumeCalls++ |
| 33 | + ch := make(chan libamqp.Delivery, 2) |
| 34 | + ch <- libamqp.Delivery{ |
| 35 | + Body: []byte(`{"no": 1, "aggregate_id": "8150276e-34fe-49d9-aeae-a35af0040a4f"}`), |
| 36 | + } |
| 37 | + ch <- libamqp.Delivery{ |
| 38 | + Body: []byte(`{"no": 2, "aggregate_id": "8150276e-34fe-49d9-aeae-a35af0040a4f"}`), |
| 39 | + } |
| 40 | + return nil, ch, nil |
| 41 | + } |
| 42 | + triggerCalls := 0 |
| 43 | + trigger := func(ctx context.Context, notification *sql.ProjectionNotification) error { |
| 44 | + triggerCalls++ |
| 45 | + switch triggerCalls { |
| 46 | + case 1: |
| 47 | + ensure.Equal(&sql.ProjectionNotification{No: 1, AggregateID: "8150276e-34fe-49d9-aeae-a35af0040a4f"}, notification) |
| 48 | + case 2: |
| 49 | + ensure.Equal(&sql.ProjectionNotification{No: 2, AggregateID: "8150276e-34fe-49d9-aeae-a35af0040a4f"}, notification) |
| 50 | + ctxCancel() |
| 51 | + default: |
| 52 | + ensure.Fail("Only 2 calls to trigger where expected") |
| 53 | + } |
| 54 | + return nil |
| 55 | + } |
| 56 | + logger, loggerHook := getLogger() |
| 57 | + |
| 58 | + listener, err := amqp.NewListener(consume, time.Millisecond, time.Millisecond, logger) |
| 59 | + ensure.NoError(err) |
| 60 | + |
| 61 | + err = listener.Listen(ctx, trigger) |
| 62 | + |
| 63 | + ensure.Equal(context.Canceled, err) |
| 64 | + ensure.Equal(1, consumeCalls) |
| 65 | + ensure.Equal(2, triggerCalls) |
| 66 | + ensure.Len(loggerHook.Entries, 0) |
| 67 | + }) |
| 68 | + |
| 69 | + t.Run("Reconnect with exponential back-off", func(t *testing.T) { |
| 70 | + ensure := require.New(t) |
| 71 | + |
| 72 | + ctx, ctxCancel := context.WithTimeout(context.Background(), time.Second) |
| 73 | + defer ctxCancel() |
| 74 | + |
| 75 | + var consumeCalls []time.Time |
| 76 | + consume := func() (io.Closer, <-chan libamqp.Delivery, error) { |
| 77 | + consumeCalls = append(consumeCalls, time.Now()) |
| 78 | + if len(consumeCalls) == 5 { |
| 79 | + ctxCancel() |
| 80 | + } |
| 81 | + |
| 82 | + return nil, nil, fmt.Errorf("failure %d", len(consumeCalls)) |
| 83 | + } |
| 84 | + |
| 85 | + logger, loggerHook := getLogger() |
| 86 | + |
| 87 | + listener, err := amqp.NewListener(consume, time.Millisecond, 6*time.Millisecond, logger) |
| 88 | + ensure.NoError(err) |
| 89 | + |
| 90 | + err = listener.Listen(ctx, func(ctx context.Context, notification *sql.ProjectionNotification) error { |
| 91 | + ensure.Fail("Trigger should ever be called") |
| 92 | + return nil |
| 93 | + }) |
| 94 | + |
| 95 | + ensure.Equal(context.Canceled, err) |
| 96 | + |
| 97 | + reconnectIntervals := []time.Duration{time.Millisecond, time.Millisecond * 2, time.Millisecond * 4, time.Millisecond * 6, time.Millisecond * 6} |
| 98 | + ensure.Len(consumeCalls, len(reconnectIntervals)) |
| 99 | + for i := 1; i < len(reconnectIntervals); i++ { |
| 100 | + expectedInterval := reconnectIntervals[i-1] |
| 101 | + interval := consumeCalls[i].Sub(consumeCalls[i-1]) |
| 102 | + |
| 103 | + if expectedInterval > interval || interval > (expectedInterval+time.Millisecond) { |
| 104 | + assert.Fail(t, fmt.Sprintf("Invalid interval after consume %d (got %s expected between %s and %s)", i, interval, expectedInterval, (expectedInterval+time.Millisecond))) |
| 105 | + } |
| 106 | + } |
| 107 | + |
| 108 | + // Ensure we get log output |
| 109 | + logEntries := loggerHook.AllEntries() |
| 110 | + ensure.Len(logEntries, len(reconnectIntervals)) |
| 111 | + for i, log := range logEntries { |
| 112 | + assert.Equal(t, log.Level, logrus.ErrorLevel) |
| 113 | + assert.Equal(t, log.Message, "failed to start consuming amqp messages") |
| 114 | + assert.Equal(t, fmt.Errorf("failure %d", i+1), log.Data["error"]) |
| 115 | + assert.Equal(t, reconnectIntervals[i].String(), log.Data["reconnect_in"]) |
| 116 | + } |
| 117 | + }) |
| 118 | + |
| 119 | + t.Run("Listen, consume and reconnect", func(t *testing.T) { |
| 120 | + ensure := require.New(t) |
| 121 | + |
| 122 | + ctx, ctxCancel := context.WithTimeout(context.Background(), time.Second) |
| 123 | + defer ctxCancel() |
| 124 | + |
| 125 | + consumeCalls := 0 |
| 126 | + consume := func() (io.Closer, <-chan libamqp.Delivery, error) { |
| 127 | + consumeCalls++ |
| 128 | + ch := make(chan libamqp.Delivery, 2) |
| 129 | + ch <- libamqp.Delivery{ |
| 130 | + Body: []byte(`{"no": 1, "aggregate_id": "8150276e-34fe-49d9-aeae-a35af0040a4f"}`), |
| 131 | + } |
| 132 | + ch <- libamqp.Delivery{ |
| 133 | + Body: []byte(`{"no": 2, "aggregate_id": "8150276e-34fe-49d9-aeae-a35af0040a4f"}`), |
| 134 | + } |
| 135 | + close(ch) |
| 136 | + return nil, ch, nil |
| 137 | + } |
| 138 | + triggerCalls := 0 |
| 139 | + trigger := func(ctx context.Context, notification *sql.ProjectionNotification) error { |
| 140 | + triggerCalls++ |
| 141 | + switch triggerCalls { |
| 142 | + case 1, 3: |
| 143 | + ensure.Equal(&sql.ProjectionNotification{No: 1, AggregateID: "8150276e-34fe-49d9-aeae-a35af0040a4f"}, notification) |
| 144 | + case 2, 4: |
| 145 | + ensure.Equal(&sql.ProjectionNotification{No: 2, AggregateID: "8150276e-34fe-49d9-aeae-a35af0040a4f"}, notification) |
| 146 | + default: |
| 147 | + ensure.Fail("Only 2 calls to trigger where expected") |
| 148 | + } |
| 149 | + if triggerCalls == 4 { |
| 150 | + ctxCancel() |
| 151 | + } |
| 152 | + return nil |
| 153 | + } |
| 154 | + |
| 155 | + logger, loggerHook := getLogger() |
| 156 | + |
| 157 | + listener, err := amqp.NewListener(consume, time.Millisecond, time.Millisecond, logger) |
| 158 | + ensure.NoError(err) |
| 159 | + |
| 160 | + err = listener.Listen(ctx, trigger) |
| 161 | + |
| 162 | + ensure.Equal(context.Canceled, err) |
| 163 | + ensure.Equal(2, consumeCalls) |
| 164 | + ensure.Equal(4, triggerCalls) |
| 165 | + ensure.Len(loggerHook.Entries, 0) |
| 166 | + }) |
| 167 | +} |
| 168 | + |
| 169 | +func getLogger() (goengine.Logger, *test.Hook) { |
| 170 | + logger, loggerHook := test.NewNullLogger() |
| 171 | + logger.SetLevel(logrus.DebugLevel) |
| 172 | + |
| 173 | + return goengineLogger.Wrap(logger), loggerHook |
| 174 | +} |
0 commit comments