No Processor Available

Hi,

I encountered this issue when trying to export pdf file in Omero.Figure. The issue went away after i restarted the server. Below are the logs

Blitz

2019-08-14 02:54:18,773 INFO  [                      omero.cmd.SessionI] (1-thread-3) Unregistered servant:ProcessorCallback/849b6328-aa75-4997-9bd1-6c318050b168(omero.grid._ProcessorCallbackTie@17fc1185)
2019-08-14 02:54:18,775 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Executor.doWork -- ome.services.blitz.impl.SharedResourcesI.updateJob[]
2019-08-14 02:54:18,775 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Args:    [null, InternalSF@1920939741]
2019-08-14 02:54:18,802 INFO  [         ome.security.basic.EventHandler] (1-thread-3)  Auth:    user=0,group=0,event=7978(User),sess=1a45dd93-98ff-41a6-9df4-bcfa06e41fa1
2019-08-14 02:54:18,850 INFO  [       ome.security.basic.CurrentDetails] (1-thread-3) Adding log:UPDATE,class ome.model.jobs.ParseJob,767
2019-08-14 02:54:18,867 INFO  [                 org.perf4j.TimingLogger] (1-thread-3) start[1565751258775] time[91] tag[omero.call.success.ome.services.blitz.impl.SharedResourcesI$7.doWork]
2019-08-14 02:54:18,867 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Rslt:    null
2019-08-14 02:54:18,874 ERROR [     ome.services.blitz.util.ParamsCache] (1-thread-3) Failed to load params for 40
omero.NoProcessorAvailable: null
        at ome.services.blitz.impl.SharedResourcesI.acquireProcessor(SharedResourcesI.java:442) ~[omero-blitz.jar:5.5.2]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_212]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_212]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_212]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_212]
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:333) ~[spring-aop.jar:4.3.14.RELEASE]
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190) ~[spring-aop.jar:4.3.14.RELEASE]
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157) ~[spring-aop.jar:4.3.14.RELEASE]
        at omero.cmd.CallContext.invoke(CallContext.java:85) ~[omero-blitz.jar:5.5.2]
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179) ~[spring-aop.jar:4.3.14.RELEASE]
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:213) ~[spring-aop.jar:4.3.14.RELEASE]
        at com.sun.proxy.$Proxy85.acquireProcessor(Unknown Source) ~[na:na]
        at omero.grid.ParamsHelper.generateScriptParams(ParamsHelper.java:147) ~[omero-blitz.jar:5.5.2]
        at ome.services.blitz.util.ParamsCache$Loader.createParams(ParamsCache.java:304) ~[omero-blitz.jar:5.5.2]
        at ome.services.blitz.util.ParamsCache._load(ParamsCache.java:217) [omero-blitz.jar:5.5.2]
        at ome.services.blitz.util.ParamsCache.lookupAll(ParamsCache.java:181) [omero-blitz.jar:5.5.2]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_212]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_212]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_212]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_212]
        at org.springframework.util.MethodInvoker.invoke(MethodInvoker.java:265) [spring-core.jar:4.3.22.RELEASE]
        at org.springframework.scheduling.quartz.MethodInvokingJobDetailFactoryBean$MethodInvokingJob.executeInternal(MethodInvokingJobDetailFactoryBean.java:257) [spring-context-support.jar:4.3.22.RELEASE]
        at org.springframework.scheduling.quartz.QuartzJobBean.execute(QuartzJobBean.java:75) [spring-context-support.jar:4.3.22.RELEASE]
        at org.quartz.core.JobRunShell.run(JobRunShell.java:202) [quartz.jar:na]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_212]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_212]
        at java.lang.Thread.run(Thread.java:748) [na:1.8.0_212]
2019-08-14 02:54:18,876 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Executor.doWork -- ome.services.blitz.impl.SharedResourcesI.submitJob[]
2019-08-14 02:54:18,876 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Args:    [null, InternalSF@1920939741]
2019-08-14 02:54:18,901 INFO  [         ome.security.basic.EventHandler] (1-thread-3)  Auth:    user=0,group=0,event=7979(User),sess=1a45dd93-98ff-41a6-9df4-bcfa06e41fa1
2019-08-14 02:54:18,925 INFO  [       ome.security.basic.CurrentDetails] (1-thread-3) Adding log:INSERT,class ome.model.jobs.ParseJob,768
2019-08-14 02:54:18,928 INFO  [       ome.security.basic.CurrentDetails] (1-thread-3) Adding log:INSERT,class ome.model.jobs.JobOriginalFileLink,1418
2019-08-14 02:54:18,967 INFO  [                 org.perf4j.TimingLogger] (1-thread-3) start[1565751258876] time[91] tag[omero.call.success.ome.services.blitz.impl.SharedResourcesI$6.doWork]
2019-08-14 02:54:18,970 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Rslt:    ome.model.jobs.ParseJob:Id_768
2019-08-14 02:54:18,971 INFO  [                      omero.cmd.SessionI] (1-thread-3) Added servant to adapter: ProcessorCallback/8e8d5757-9057-494b-ba26-8bd2e591b017(omero.grid._ProcessorCallbackTie@ba66a16f)
2019-08-14 02:54:18,973 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Executor.doWork -- ome.services.blitz.impl.ServiceFactoryI.getEventContext[]
2019-08-14 02:54:18,974 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Args:    [null, InternalSF@1920939741]
2019-08-14 02:54:18,984 INFO  [         ome.security.basic.EventHandler] (1-thread-3)  Auth:    user=0,group=0,event=null(User),sess=1a45dd93-98ff-41a6-9df4-bcfa06e41fa1
2019-08-14 02:54:18,991 INFO  [                 org.perf4j.TimingLogger] (1-thread-3) start[1565751258974] time[17] tag[omero.call.success.ome.services.blitz.impl.ServiceFactoryI$2.doWork]
2019-08-14 02:54:18,992 INFO  [        ome.services.util.ServiceHandler] (1-thread-3)  Rslt:    ome.system.SimpleEventContext@552b5e3d

Processor

2019-08-14 00:01:31,094 INFO  [                omero.processor.ProcessI] (Thread-2  ) <proc:12410,rc=1,uuid=76816c7b-d525-49ee-8866-14124ae54e53> : Keep alive failed
2019-08-14 00:01:31,095 INFO  [                            omero.remote] (Thread-2  )  Meth: ProcessI.poll
2019-08-14 00:01:31,095 INFO  [                            omero.remote] (Thread-2  )  Rslt: object #0 (::omero::RInt)
{
    _val = 1
}
2019-08-14 00:01:31,120 INFO  [                omero.processor.ProcessI] (Thread-2  ) <proc:12392,rc=1,uuid=84a8d987-227a-4865-ba43-8d3a51263a68> : Keep alive failed
2019-08-14 02:52:56,971 INFO  [                       omero.util.Server] (MainThread) ********************************************************************************
2019-08-14 02:52:56,971 INFO  [                       omero.util.Server] (MainThread) Waiting 10000 ms on startup
2019-08-14 02:53:06,972 INFO  [                       omero.util.Server] (MainThread) Starting
2019-08-14 02:53:06,997 INFO  [                    omero.util.Resources] (Thread-2  ) Starting
2019-08-14 02:53:49,120 INFO  [                             omero.utils] (MainThread) Failed to get session on attempt 1
2019-08-14 02:53:59,381 INFO  [              omero.processor.ProcessorI] (MainThread) Registering processor Processor -t -e 1.1 @ Processor-0.ProcessorAdapter
2019-08-14 02:53:59,483 INFO  [                       omero.util.Server] (MainThread) Entering main loop

Is this log trying to say the processor is not responding? And is there any other logs i should look into it?

Thanks.

Cwtan

Hi @Cwtan,

the Blitz log is definitely saying that the Processor stopped responding. In the Processor log:

2019-08-14 00:01:31,120 INFO ...
2019-08-14 02:52:56,971 INFO ...

looks to point to a freeze of some form, but there’s no indication of what might have caused it.

~Josh

Hi Josh,

Thanks for the reply, so far the issue never reappear. So just to ask, possible to create more processors so that it will reduce the chances of issue reappearing?

Cwtan

Hi @cwtan,

it is possible to add more processors if you’d like. However, since I’m unsure of the cause, I can’t say whether it will reduce the likelihood.

~J.