Recurring warning message in log

Support
#1

Hi

On my app in production I have the following message cluttering the log.

18:25:16.909 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:16.924 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:16.989 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:17.009 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:17.038 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:17.199 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:17.247 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:17.271 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:17.304 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:17.342 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:18.860 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:18.873 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:18.896 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:18.908 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:18.933 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:24.835 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:24.847 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:24.868 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:24.876 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:25:24.896 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:30:24.331 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:31:36.329 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:31:59.745 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:31:59.753 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:31:59.771 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:31:59.775 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:31:59.793 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:32:36.419 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:32:36.430 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:32:36.449 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start
18:32:36.455 WARN c.h.c.c.s.r.LocalServiceDirectory - Interrupted awaiting for context to start

I see where it happens in the code of LocalServiceDirectory but I’m struggling to reproduce it in test environment.


    public static LocalServiceInvoker getInvoker(String name) {
        try {
            latch.await(120, TimeUnit.SECONDS);
        } catch (InterruptedException e) {
            Thread.currentThread().interrupt();
            log.warn("Interrupted awaiting for context to start");
        }
        return invokers.get(name);
    }

I understand it is related to the sync’ing of threads calling services through proxies. Maybe there is some tuning needed. I have no clear evidence about performance being impacted or not (app is running and used by 10 people for now).

Note sure if I give you enough information, but that’s worth a try.

Regards
Michael