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