Added PHC-related logs

Signed-off-by: Roman Zavodskikh <[email protected]>

proxy/healthy_endpoints.go

@@ -11,7 +11,7 @@ type healthyEndpoints struct {
 	endpointRegistry *routing.EndpointRegistry
 }
 
-func (h *healthyEndpoints) filterHealthyEndpoints(endpoints []routing.LBEndpoint, rt *routing.Route) []routing.LBEndpoint {
+func (h *healthyEndpoints) filterHealthyEndpoints(ctx *context, endpoints []routing.LBEndpoint) []routing.LBEndpoint {
 	if h == nil {
 		return endpoints
 	}
@@ -21,7 +21,7 @@ func (h *healthyEndpoints) filterHealthyEndpoints(endpoints []routing.LBEndpoint
 	filtered := make([]routing.LBEndpoint, 0, len(endpoints))
 	for _, e := range endpoints {
 		if p < e.Metrics.HealthCheckDropProbability() {
-			/* drop */
+			ctx.Logger().Infof("dropping endpoint %q due to passive health check", e.Host)
 		} else {
 			filtered = append(filtered, e)
 		}

proxy/proxy.go

@@ -540,7 +540,7 @@ func (p *Proxy) selectEndpoint(ctx *context) *routing.LBEndpoint {
 	rt := ctx.route
 	endpoints := rt.LBEndpoints
 	endpoints = p.fadein.filterFadeIn(endpoints, rt)
-	endpoints = p.heathlyEndpoints.filterHealthyEndpoints(endpoints, rt)
+	endpoints = p.heathlyEndpoints.filterHealthyEndpoints(ctx, endpoints)
 
 	lbctx := &routing.LBContext{
 		Request:     ctx.request,
@@ -791,8 +791,9 @@ func WithParams(p Params) *Proxy {
 		defaultHTTPStatus = p.DefaultHTTPStatus
 	}
 
+	logger := &logging.DefaultLog{}
 	if p.EndpointRegistry == nil {
-		p.EndpointRegistry = routing.NewEndpointRegistry(routing.RegistryOptions{})
+		p.EndpointRegistry = routing.NewEndpointRegistry(routing.RegistryOptions{Logger: logger})
 	}
 
 	hostname := os.Getenv("HOSTNAME")
@@ -823,7 +824,7 @@ func WithParams(p Params) *Proxy {
 		maxLoops:                 p.MaxLoopbacks,
 		breakers:                 p.CircuitBreakers,
 		limiters:                 p.RateLimiters,
-		log:                      &logging.DefaultLog{},
+		log:                      logger,
 		defaultHTTPStatus:        defaultHTTPStatus,
 		tracing:                  newProxyTracing(p.OpenTracing),
 		accessLogDisabled:        p.AccessLogDisabled,

routing/endpointregistry.go

@@ -6,6 +6,7 @@ import (
 	"time"
 
 	"github.com/zalando/skipper/eskip"
+	"github.com/zalando/skipper/logging"
 )
 
 const defaultLastSeenTimeout = 1 * time.Minute
@@ -100,8 +101,9 @@ type EndpointRegistry struct {
 
 	quit chan struct{}
 
-	now  func() time.Time
-	data sync.Map // map[string]*entry
+	now    func() time.Time
+	data   sync.Map // map[string]*entry
+	logger logging.Logger
 }
 
 var _ PostProcessor = &EndpointRegistry{}
@@ -112,6 +114,7 @@ type RegistryOptions struct {
 	StatsResetPeriod              time.Duration
 	MinRequests                   int64
 	MaxHealthCheckDropProbability float64
+	Logger                        logging.Logger
 }
 
 func (r *EndpointRegistry) Do(routes []*Route) []*Route {
@@ -166,6 +169,9 @@ func (r *EndpointRegistry) updateStats() {
 			requests := e.totalRequests[curSlot].Load()
 			if requests > r.minRequests {
 				failedRoundTripsRatio := float64(failed) / float64(requests)
+				if failedRoundTripsRatio > 0 {
+					r.logger.Infof("passive health check: marking %q as unhealthy due to failed round trips ratio: %f", key, failedRoundTripsRatio)
+				}
 				e.healthCheckDropProbability.Store(min(failedRoundTripsRatio, r.maxHealthCheckDropProbability))
 			} else {
 				e.healthCheckDropProbability.Store(0.0)
@@ -192,6 +198,10 @@ func NewEndpointRegistry(o RegistryOptions) *EndpointRegistry {
 		o.LastSeenTimeout = defaultLastSeenTimeout
 	}
 
+	if o.Logger == nil {
+		o.Logger = &logging.DefaultLog{}
+	}
+
 	registry := &EndpointRegistry{
 		lastSeenTimeout:               o.LastSeenTimeout,
 		statsResetPeriod:              o.StatsResetPeriod,
@@ -200,8 +210,9 @@ func NewEndpointRegistry(o RegistryOptions) *EndpointRegistry {
 
 		quit: make(chan struct{}),
 
-		now:  time.Now,
-		data: sync.Map{},
+		now:    time.Now,
+		data:   sync.Map{},
+		logger: o.Logger,
 	}
 	if o.PassiveHealthCheckEnabled {
 		go registry.updateStats()