Log parse duration errors

CHANGELOG.md

@@ -9,6 +9,7 @@ Unreleased changes are available as `avenga/couper:edge` container.
     * provided via `-d` command-line flag or `COUPER_FILE_DIRECTORY` environment variable
   * `beta_health`-block to `backend`-block to enable continuous health-checks for defined backends ([#313](https://github.com/avenga/couper/pull/313))
     * `backends.<name>.health` variable to access the current health-check state _(subject to change)_
+  * Log malformed duration settings ([#487](https://github.com/avenga/couper/pull/487))
 
 * **Changed**
   * Permission handling: ([#477](https://github.com/avenga/couper/pull/477))

handler/transport/backend.go

@@ -504,7 +504,7 @@ func (b *Backend) evalTransport(httpCtx *hcl.EvalContext, params hcl.Body, req *
 
 	return b.transportConf.
 		WithTarget(originURL.Scheme, originURL.Host, hostname, proxyURL).
-		WithTimings(connectTimeout, ttfbTimeout, timeout), nil
+		WithTimings(connectTimeout, ttfbTimeout, timeout, log), nil
 }
 
 // setUserAgent sets an empty one if none is present or empty

handler/transport/transport.go

@@ -152,17 +152,20 @@ func (c *Config) WithTarget(scheme, origin, hostname, proxyURL string) *Config {
 	return &conf
 }
 
-func (c *Config) WithTimings(connect, ttfb, timeout string) *Config {
+func (c *Config) WithTimings(connect, ttfb, timeout string, logger *logrus.Entry) *Config {
 	conf := *c
-	parseDuration(connect, &conf.ConnectTimeout)
-	parseDuration(ttfb, &conf.TTFBTimeout)
-	parseDuration(timeout, &conf.Timeout)
+	parseDuration(connect, &conf.ConnectTimeout, logger)
+	parseDuration(ttfb, &conf.TTFBTimeout, logger)
+	parseDuration(timeout, &conf.Timeout, logger)
 	return &conf
 }
 
 // parseDuration sets the target value if the given duration string is not empty.
-func parseDuration(src string, target *time.Duration) {
+func parseDuration(src string, target *time.Duration, logger *logrus.Entry) {
 	d, err := time.ParseDuration(src)
+	if err != nil {
+		logger.WithError(err).Warning("using default timing of ", target, " because an error occured")
+	}
 	if src != "" && err != nil {
 		return
 	}

handler/transport/transport_test.go

@@ -1,13 +1,18 @@
 package transport
 
 import (
+	"context"
 	"testing"
 	"time"
+
+	"github.com/sirupsen/logrus"
 )
 
 func Test_parseDuration(t *testing.T) {
+	log := logrus.New()
+
 	var target time.Duration
-	parseDuration("1ms", &target)
+	parseDuration("1ms", &target, log.WithContext(context.TODO()))
 
 	if target != 1000000 {
 		t.Errorf("Unexpected duration given: %#v", target)

server/http_test.go

@@ -689,3 +689,24 @@ func TestHTTPServer_RequestID(t *testing.T) {
 		})
 	}
 }
+
+func TestHTTPServer_parseDuration(t *testing.T) {
+	helper := test.New(t)
+	client := newClient()
+
+	shutdown, logHook := newCouper("testdata/integration/config/16_couper.hcl", test.New(t))
+	defer shutdown()
+
+	logHook.Reset()
+	req, err := http.NewRequest(http.MethodGet, "http://anyserver:8080/", nil)
+	helper.Must(err)
+
+	_, err = client.Do(req)
+	helper.Must(err)
+
+	logs := logHook.AllEntries()
+
+	if logs[0].Message != `using default timing of 0s because an error occured: time: invalid duration "xxx"` {
+		t.Errorf("%#v", logs[0].Message)
+	}
+}

server/testdata/integration/config/16_couper.hcl

@@ -0,0 +1,10 @@
+server {
+  endpoint "/" {
+    proxy {
+      backend {
+        origin = "${env.COUPER_TEST_BACKEND_ADDR}"
+        timeout = "xxx"
+      }
+    }
+  }
+}