Open Microscopy Environment

Hi

I've uploaded 100k images to omero using the --no_thumbnails option.
So maybe not unsurprisingly when we try and access the images via the thumbnail url many don't show up and we get the subject line error message "Failed to render thumbnail" in the html page returned.

So is thumbnail generation happening as a background thread (and if so, how do I tell if it's going or not?) or do I have to do something to generate the thumbnails?

So this is an Edit: just thinking about it - we use 200x200 for our thumbnails so actually the no_thumbnail option probably wouldn't have caused the behaviour we are seeing?

our web page is here: https://dev.mousephenotype.org/data/sea ... mpc_images

if I access a render_image url it displays and then a subsequent access to the thumbnail then also displays where previously it didn't...

Thanks

Jonathan.

Hi Jonathan,

is there anything relevant in the log files? I'm certainly seeing the broken thumbnail icons on your page, but some either nginx error or OMERO.web error (and possibly OMERO.server error) would be very useful.

Cheers,
~Josh.

ok - sorry that was probably annoying to not have any logs!

OMEROweb.log:

2015-02-25 09:45:53,109 WARNI [ omero.gateway] (proc.04451) debug:3617 ApiUsageException on <class 'omeroweb.webclient.webclient_gateway.OmeroWebSafeCallWrapper'> to <7ea564eb-e590-4c7c-bece-30ab778ca20fomero.api.ThumbnailStore> resetDefaults((<ServiceOptsDict: {'omero.session.uuid': '64e53de1-7fd6-4b7f-bf26-63e26d54eafb', 'omero.group': '3', 'omero.client.uuid': '4f23cf4a-0e96-4902-95f3-50c9bad0c4f1'}>,), {})
Traceback (most recent call last):
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero/gateway/__init__.py", line 3635, in __call__
return self.f(*args, **kwargs)
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero_api_ThumbnailStore_ice.py", line 323, in resetDefaults
return _M_omero.api.ThumbnailStore._op_resetDefaults.invoke(self, ((), _ctx))
ApiUsageException: exception ::omero::ApiUsageException
{
serverStackTrace = ome.conditions.ApiUsageException: Unable to reset rendering settings in a read-only group for Pixels set id:76578
at ome.services.ThumbnailBean.resetDefaults(ThumbnailBean.java:1267)
at sun.reflect.GeneratedMethodAccessor521.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at ome.security.basic.EventHandler.invoke(EventHandler.java:154)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.tools.hibernate.SessionHandler.doStateful(SessionHandler.java:218)
at ome.tools.hibernate.SessionHandler.invoke(SessionHandler.java:202)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:241)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy86.resetDefaults(Unknown Source)
at sun.reflect.GeneratedMethodAccessor521.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at ome.security.basic.BasicSecurityWiring.invoke(BasicSecurityWiring.java:98)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.services.blitz.fire.AopContextInitializer.invoke(AopContextInitializer.java:43)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy86.resetDefaults(Unknown Source)
at sun.reflect.GeneratedMethodAccessor573.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at ome.services.blitz.util.IceMethodInvoker.invoke(IceMethodInvoker.java:179)
at ome.services.throttling.Callback.run(Callback.java:56)
at ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)
at ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:149)
at ome.services.blitz.impl.ThumbnailStoreI.resetDefaults_async(ThumbnailStoreI.java:136)
at sun.reflect.GeneratedMethodAccessor572.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at omero.cmd.CallContext.invoke(CallContext.java:78)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy87.resetDefaults_async(Unknown Source)
at omero.api._ThumbnailStoreTie.resetDefaults_async(_ThumbnailStoreTie.java:168)
at omero.api._ThumbnailStoreDisp.___resetDefaults(_ThumbnailStoreDisp.java:579)
at omero.api._ThumbnailStoreDisp.__dispatch(_ThumbnailStoreDisp.java:713)
at IceInternal.Incoming.invoke(Incoming.java:222)
at Ice.ConnectionI.invokeAll(ConnectionI.java:2482)
at Ice.ConnectionI.dispatch(ConnectionI.java:1258)
at Ice.ConnectionI.message(ConnectionI.java:1213)
at IceInternal.ThreadPool.run(ThreadPool.java:321)
at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:693)
at java.lang.Thread.run(Thread.java:701)

serverExceptionClass = ome.conditions.ApiUsageException
message = Unable to reset rendering settings in a read-only group for Pixels set id:76578
}
2015-02-25 09:45:53,118 ERROR [ omero.gateway] (proc.04451) getThumbnail:6518 Traceback (most recent call last):
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero/gateway/__init__.py", line 6465, in getThumbnail
tb = self._prepareTB()
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero/gateway/__init__.py", line 6362, in _prepareTB
tb.resetDefaults(ctx) # E.g. May throw Missing Pyramid Exception
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero/gateway/__init__.py", line 3638, in __call__
return self.handle_exception(e, *args, **kwargs)
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omeroweb/webclient/webclient_gateway.py", line 1980, in handle_exception
e, *args, **kwargs)
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero/gateway/__init__.py", line 3635, in __call__
return self.f(*args, **kwargs)
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero_api_ThumbnailStore_ice.py", line 323, in resetDefaults
return _M_omero.api.ThumbnailStore._op_resetDefaults.invoke(self, ((), _ctx))
ApiUsageException: exception ::omero::ApiUsageException
{
serverStackTrace = ome.conditions.ApiUsageException: Unable to reset rendering settings in a read-only group for Pixels set id:76578
at ome.services.ThumbnailBean.resetDefaults(ThumbnailBean.java:1267)
at sun.reflect.GeneratedMethodAccessor521.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at ome.security.basic.EventHandler.invoke(EventHandler.java:154)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.tools.hibernate.SessionHandler.doStateful(SessionHandler.java:218)
at ome.tools.hibernate.SessionHandler.invoke(SessionHandler.java:202)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:241)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy86.resetDefaults(Unknown Source)
at sun.reflect.GeneratedMethodAccessor521.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at ome.security.basic.BasicSecurityWiring.invoke(BasicSecurityWiring.java:98)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.services.blitz.fire.AopContextInitializer.invoke(AopContextInitializer.java:43)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy86.resetDefaults(Unknown Source)
at sun.reflect.GeneratedMethodAccessor573.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at ome.services.blitz.util.IceMethodInvoker.invoke(IceMethodInvoker.java:179)
at ome.services.throttling.Callback.run(Callback.java:56)
at ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)
at ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:149)
at ome.services.blitz.impl.ThumbnailStoreI.resetDefaults_async(ThumbnailStoreI.java:136)
at sun.reflect.GeneratedMethodAccessor572.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at omero.cmd.CallContext.invoke(CallContext.java:78)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy87.resetDefaults_async(Unknown Source)
at omero.api._ThumbnailStoreTie.resetDefaults_async(_ThumbnailStoreTie.java:168)
at omero.api._ThumbnailStoreDisp.___resetDefaults(_ThumbnailStoreDisp.java:579)
at omero.api._ThumbnailStoreDisp.__dispatch(_ThumbnailStoreDisp.java:713)
at IceInternal.Incoming.invoke(Incoming.java:222)
at Ice.ConnectionI.invokeAll(ConnectionI.java:2482)
at Ice.ConnectionI.dispatch(ConnectionI.java:1258)
at Ice.ConnectionI.message(ConnectionI.java:1213)
at IceInternal.ThreadPool.run(ThreadPool.java:321)
at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:693)
at java.lang.Thread.run(Thread.java:701)

serverExceptionClass = ome.conditions.ApiUsageException
message = Unable to reset rendering settings in a read-only group for Pixels set id:76578
}

2015-02-25 09:45:53,124 DEBUG [ webgateway.views] (proc.04451) render_thumbnail:297 (c)Image 76578 not found...
2015-02-25 09:45:53,131 DEBUG [ omeroweb.decorators] (proc.04451) wrapped:390 Doing connection cleanup? True

also Blitz.log:

2015-02-25 10:14:25,531 INFO [ ome.security.basic.BasicEventContext] (erver-1085) cctx: group=-1
2015-02-25 10:14:25,531 INFO [ ome.security.basic.EventHandler] (erver-1082) Auth: user=2,group=3,event=2816844(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,532 INFO [ org.perf4j.TimingLogger] (erver-1082) start[1424859265527] time[5] tag[omero.call.exception]
2015-02-25 10:14:25,532 INFO [ ome.services.util.ServiceHandler] (erver-1082) Excp: ome.conditions.ApiUsageException: Unable to reset rendering settings in a read-only group for Pixels set id:59003
2015-02-25 10:14:25,532 INFO [ ome.security.basic.BasicEventContext] (erver-1084) cctx: group=-1
2015-02-25 10:14:25,533 INFO [ ome.security.basic.EventHandler] (erver-1084) Auth: user=2,group=-1,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,533 INFO [ ome.security.basic.EventHandler] (erver-1085) Auth: user=2,group=-1,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,537 INFO [ org.perf4j.TimingLogger] (erver-1085) start[1424859265531] time[5] tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2015-02-25 10:14:25,537 INFO [ ome.services.util.ServiceHandler] (erver-1085) Rslt: ome.model.core.Image:Id_64235
2015-02-25 10:14:25,542 INFO [ omero.cmd.SessionI] (erver-1083) Added servant to adapter: 6c5844b3-f465-43b8-a145-8e77a0059f72/d6424bce-2d0e-4734-8823-5870d2bc9196omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@a0013076)
2015-02-25 10:14:25,543 INFO [ ome.services.util.ServiceHandler] (erver-1083) Meth: interface ome.api.ThumbnailStore.setPixelsId
2015-02-25 10:14:25,543 INFO [ ome.services.util.ServiceHandler] (erver-1083) Args: [71196]
2015-02-25 10:14:25,544 INFO [ ome.security.basic.BasicEventContext] (erver-1083) cctx: group=3
2015-02-25 10:14:25,547 INFO [ org.perf4j.TimingLogger] (erver-1084) start[1424859265525] time[21] tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2015-02-25 10:14:25,547 INFO [ ome.services.util.ServiceHandler] (erver-1084) Rslt: ome.model.core.Image:Id_73637
2015-02-25 10:14:25,549 INFO [ ome.security.basic.EventHandler] (erver-1083) Auth: user=2,group=3,event=2816845(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,552 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265549] time[3] tag[omero.bulkLoadRenderingSettings]
2015-02-25 10:14:25,553 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265552] time[0] tag[omero.loadMissingPixels]
2015-02-25 10:14:25,555 INFO [ omero.cmd.SessionI] (erver-1086) Added servant to adapter: 6c5844b3-f465-43b8-a145-8e77a0059f72/21111e56-e258-438b-8239-d192aaf50282omero.api.IContainer(omero.api._IContainerTie@37a146bd)
2015-02-25 10:14:25,556 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265553] time[2] tag[omero.bulkLoadOwnerRenderingSettings]
2015-02-25 10:14:25,556 INFO [ ome.services.util.ServiceHandler] (erver-1086) Meth: interface ome.api.IContainer.getImages
2015-02-25 10:14:25,557 INFO [ ome.services.util.ServiceHandler] (erver-1086) Args: [class ome.model.core.Image, (64235), null]
2015-02-25 10:14:25,557 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265543] time[14] tag[omero.call.success.ome.services.ThumbnailBean.setPixelsId]
2015-02-25 10:14:25,557 INFO [ ome.services.util.ServiceHandler] (erver-1083) Rslt: false
2015-02-25 10:14:25,558 INFO [ ome.security.basic.BasicEventContext] (erver-1086) cctx: group=3
2015-02-25 10:14:25,558 INFO [ ome.security.basic.EventHandler] (erver-1086) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,559 INFO [ omero.cmd.SessionI] (erver-1087) Added servant to adapter: 6c5844b3-f465-43b8-a145-8e77a0059f72/064e2bc9-53c0-4fb8-9a0f-0e7841c4ffd5omero.api.IAdmin(omero.api._IAdminTie@5037ce51)
2015-02-25 10:14:25,560 INFO [ ome.services.util.ServiceHandler] (erver-1087) Meth: interface ome.api.IAdmin.getEventContext
2015-02-25 10:14:25,560 INFO [ ome.services.util.ServiceHandler] (erver-1087) Args: ()
2015-02-25 10:14:25,563 INFO [ ome.services.util.ServiceHandler] (erver-1085) Meth: interface ome.api.StatefulServiceInterface.close
2015-02-25 10:14:25,563 INFO [ ome.services.util.ServiceHandler] (erver-1085) Args: ()
2015-02-25 10:14:25,562 INFO [ org.perf4j.TimingLogger] (erver-1086) start[1424859265557] time[5] tag[omero.call.success.ome.logic.PojosImpl.getImages]
2015-02-25 10:14:25,560 INFO [ omero.cmd.SessionI] (erver-1084) Added servant to adapter: 6c5844b3-f465-43b8-a145-8e77a0059f72/373b9c73-953e-4614-8cb3-75d6531d6906omero.api.IContainer(omero.api._IContainerTie@49f0a26f)
2015-02-25 10:14:25,563 INFO [ ome.services.util.ServiceHandler] (erver-1086) Rslt: (ome.model.core.Image:Id_64235)
2015-02-25 10:14:25,563 INFO [ ome.security.basic.EventHandler] (erver-1087) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,564 INFO [ ome.services.util.ServiceHandler] (thread-152) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(6c5844b3-f465-43b8-a145-8e77a0059f72)
2015-02-25 10:14:25,564 INFO [ ome.services.util.ServiceHandler] (thread-152) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:25,564 INFO [ ome.security.basic.EventHandler] (erver-1085) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,564 INFO [ org.perf4j.TimingLogger] (erver-1085) start[1424859265563] time[1] tag[omero.call.success.ome.services.ThumbnailBean.close]
2015-02-25 10:14:25,564 INFO [ ome.services.util.ServiceHandler] (erver-1085) Rslt: null
2015-02-25 10:14:25,564 INFO [ ome.services.util.ServiceHandler] (erver-1085) Cleanup: ome.services.ThumbnailBean@188db7aa
2015-02-25 10:14:25,564 INFO [ omero.cmd.SessionI] (erver-1085) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/9fd1a98a-3753-49bc-8fa5-446496ec78efomero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@9d260040)
2015-02-25 10:14:25,565 INFO [ ome.security.basic.EventHandler] (thread-152) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:25,565 INFO [ ome.services.util.ServiceHandler] (erver-1083) Meth: interface ome.api.IContainer.getImages
2015-02-25 10:14:25,565 INFO [ ome.services.util.ServiceHandler] (erver-1083) Args: [class ome.model.core.Image, (73637), null]
2015-02-25 10:14:25,566 INFO [ ome.security.basic.BasicEventContext] (erver-1083) cctx: group=3
2015-02-25 10:14:25,566 INFO [ ome.security.basic.EventHandler] (erver-1083) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,569 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265565] time[4] tag[omero.call.success.ome.logic.PojosImpl.getImages]
2015-02-25 10:14:25,569 INFO [ ome.services.util.ServiceHandler] (erver-1083) Rslt: (ome.model.core.Image:Id_73637)
2015-02-25 10:14:25,569 INFO [o.services.sessions.SessionContext$Count] (erver-1082) -Reference count: 6c5844b3-f465-43b8-a145-8e77a0059f72=3
2015-02-25 10:14:25,569 INFO [ omero.cmd.SessionI] (erver-1082) cleanupSelf(ServiceFactoryI(session-213c05d1-6943-4c68-966a-afcb78f9646c/6c5844b3-f465-43b8-a145-8e77a0059f72)).
2015-02-25 10:14:25,569 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/213c05d1-6943-4c68-966a-afcb78f9646comero.api.IContainer(omero.api._IContainerTie@90e575b1)
2015-02-25 10:14:25,569 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 213c05d1-6943-4c68-966a-afcb78f9646comero.api.IContainer
2015-02-25 10:14:25,570 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/213c05d1-6943-4c68-966a-afcb78f9646comero.api.IAdmin(omero.api._IAdminTie@6b8679f9)
2015-02-25 10:14:25,570 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 213c05d1-6943-4c68-966a-afcb78f9646comero.api.IAdmin
2015-02-25 10:14:25,570 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/213c05d1-6943-4c68-966a-afcb78f9646comero.api.IQuery(omero.api._IQueryTie@495e0563)
2015-02-25 10:14:25,570 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 213c05d1-6943-4c68-966a-afcb78f9646comero.api.IQuery
2015-02-25 10:14:25,571 INFO [ org.perf4j.TimingLogger] (thread-152) start[1424859265564] time[7] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:25,571 INFO [ ome.services.util.ServiceHandler] (thread-152) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:25,571 INFO [ org.perf4j.TimingLogger] (erver-1087) start[1424859265563] time[8] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2015-02-25 10:14:25,571 INFO [ ome.services.util.ServiceHandler] (erver-1087) Rslt: ome.system.SimpleEventContext@62411963
2015-02-25 10:14:25,572 INFO [ ome.services.util.ServiceHandler] (erver-1082) Meth: interface ome.api.ThumbnailStore.resetDefaults
2015-02-25 10:14:25,572 INFO [ ome.services.util.ServiceHandler] (erver-1082) Args: ()
2015-02-25 10:14:25,574 INFO [ ome.security.basic.BasicEventContext] (erver-1082) cctx: group=3
2015-02-25 10:14:25,578 INFO [ ome.security.basic.EventHandler] (erver-1082) Auth: user=2,group=3,event=2816846(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,578 INFO [ org.perf4j.TimingLogger] (erver-1082) start[1424859265572] time[5] tag[omero.call.exception]
2015-02-25 10:14:25,578 INFO [ ome.services.util.ServiceHandler] (erver-1082) Excp: ome.conditions.ApiUsageException: Unable to reset rendering settings in a read-only group for Pixels set id:71196
2015-02-25 10:14:25,587 INFO [ ome.services.util.ServiceHandler] (erver-1085) Meth: interface ome.api.StatefulServiceInterface.close
2015-02-25 10:14:25,587 INFO [ ome.services.util.ServiceHandler] (erver-1085) Args: ()
2015-02-25 10:14:25,588 INFO [ ome.security.basic.EventHandler] (erver-1085) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,589 INFO [ org.perf4j.TimingLogger] (erver-1085) start[1424859265587] time[1] tag[omero.call.success.ome.services.ThumbnailBean.close]
2015-02-25 10:14:25,589 INFO [ ome.services.util.ServiceHandler] (erver-1085) Rslt: null
2015-02-25 10:14:25,589 INFO [ ome.services.util.ServiceHandler] (erver-1085) Cleanup: ome.services.ThumbnailBean@1cc7e25b
2015-02-25 10:14:25,589 INFO [ omero.cmd.SessionI] (erver-1085) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/d6424bce-2d0e-4734-8823-5870d2bc9196omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@a0013076)
2015-02-25 10:14:25,590 INFO [ omero.cmd.SessionI] (erver-1084) Added servant to adapter: 6c5844b3-f465-43b8-a145-8e77a0059f72/7910e55c-376f-4eb5-9e0a-367be5d394c8omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@5a2e0761)
2015-02-25 10:14:25,591 INFO [o.services.sessions.SessionContext$Count] (erver-1084) -Reference count: 6c5844b3-f465-43b8-a145-8e77a0059f72=2
2015-02-25 10:14:25,591 INFO [ omero.cmd.SessionI] (erver-1084) cleanupSelf(ServiceFactoryI(session-064e2bc9-53c0-4fb8-9a0f-0e7841c4ffd5/6c5844b3-f465-43b8-a145-8e77a0059f72)).
2015-02-25 10:14:25,591 INFO [ omero.cmd.SessionI] (erver-1084) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/064e2bc9-53c0-4fb8-9a0f-0e7841c4ffd5omero.api.IAdmin(omero.api._IAdminTie@5037ce51)
2015-02-25 10:14:25,591 INFO [ omero.cmd.SessionI] (erver-1084) Removed servant from adapter: 064e2bc9-53c0-4fb8-9a0f-0e7841c4ffd5omero.api.IAdmin
2015-02-25 10:14:25,591 INFO [ omero.cmd.SessionI] (erver-1084) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/064e2bc9-53c0-4fb8-9a0f-0e7841c4ffd5omero.api.IContainer(omero.api._IContainerTie@75eacd8f)
2015-02-25 10:14:25,591 INFO [ omero.cmd.SessionI] (erver-1084) Removed servant from adapter: 064e2bc9-53c0-4fb8-9a0f-0e7841c4ffd5omero.api.IContainer
2015-02-25 10:14:25,591 INFO [ omero.cmd.SessionI] (erver-1084) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/064e2bc9-53c0-4fb8-9a0f-0e7841c4ffd5omero.api.IQuery(omero.api._IQueryTie@66d555fe)
2015-02-25 10:14:25,591 INFO [ omero.cmd.SessionI] (erver-1084) Removed servant from adapter: 064e2bc9-53c0-4fb8-9a0f-0e7841c4ffd5omero.api.IQuery
2015-02-25 10:14:25,591 INFO [ ome.services.util.ServiceHandler] (erver-1083) Meth: interface ome.api.ThumbnailStore.setPixelsId
2015-02-25 10:14:25,591 INFO [ ome.services.util.ServiceHandler] (erver-1083) Args: [64235]
2015-02-25 10:14:25,592 INFO [ ome.security.basic.BasicEventContext] (erver-1083) cctx: group=3
2015-02-25 10:14:25,593 INFO [ omero.cmd.SessionI] (erver-1087) Added servant to adapter: 6c5844b3-f465-43b8-a145-8e77a0059f72/48868f09-fbb4-4d8a-97db-83952986a605omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@a00ecb22)
2015-02-25 10:14:25,595 INFO [ ome.services.util.ServiceHandler] (erver-1087) Meth: interface ome.api.ThumbnailStore.setPixelsId
2015-02-25 10:14:25,595 INFO [ ome.services.util.ServiceHandler] (erver-1087) Args: [73637]
2015-02-25 10:14:25,595 INFO [ ome.security.basic.BasicEventContext] (erver-1087) cctx: group=3
2015-02-25 10:14:25,601 INFO [ ome.security.basic.EventHandler] (erver-1087) Auth: user=2,group=3,event=2816848(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,601 INFO [ ome.security.basic.EventHandler] (erver-1083) Auth: user=2,group=3,event=2816847(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,604 INFO [ org.perf4j.TimingLogger] (erver-1087) start[1424859265601] time[3] tag[omero.bulkLoadRenderingSettings]
2015-02-25 10:14:25,604 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265601] time[3] tag[omero.bulkLoadRenderingSettings]
2015-02-25 10:14:25,605 INFO [ org.perf4j.TimingLogger] (erver-1087) start[1424859265604] time[0] tag[omero.loadMissingPixels]
2015-02-25 10:14:25,605 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265604] time[0] tag[omero.loadMissingPixels]
2015-02-25 10:14:25,607 INFO [ org.perf4j.TimingLogger] (erver-1087) start[1424859265605] time[1] tag[omero.bulkLoadOwnerRenderingSettings]
2015-02-25 10:14:25,607 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265605] time[1] tag[omero.bulkLoadOwnerRenderingSettings]
2015-02-25 10:14:25,608 INFO [ org.perf4j.TimingLogger] (erver-1087) start[1424859265595] time[12] tag[omero.call.success.ome.services.ThumbnailBean.setPixelsId]
2015-02-25 10:14:25,608 INFO [ ome.services.util.ServiceHandler] (erver-1087) Rslt: false
2015-02-25 10:14:25,608 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265591] time[16] tag[omero.call.success.ome.services.ThumbnailBean.setPixelsId]
2015-02-25 10:14:25,608 INFO [ ome.services.util.ServiceHandler] (erver-1083) Rslt: false
2015-02-25 10:14:25,611 INFO [ omero.cmd.SessionI] (erver-1085) Added servant to adapter: 6c5844b3-f465-43b8-a145-8e77a0059f72/373b9c73-953e-4614-8cb3-75d6531d6906omero.api.IAdmin(omero.api._IAdminTie@810f6a24)
2015-02-25 10:14:25,612 INFO [ omero.cmd.SessionI] (erver-1085) Added servant to adapter: 6c5844b3-f465-43b8-a145-8e77a0059f72/21111e56-e258-438b-8239-d192aaf50282omero.api.IAdmin(omero.api._IAdminTie@3622475e)
2015-02-25 10:14:25,612 INFO [ ome.services.util.ServiceHandler] (erver-1085) Meth: interface ome.api.IAdmin.getEventContext
2015-02-25 10:14:25,612 INFO [ ome.services.util.ServiceHandler] (erver-1085) Args: ()
2015-02-25 10:14:25,613 INFO [ ome.services.util.ServiceHandler] (erver-1082) Meth: interface ome.api.IAdmin.getEventContext
2015-02-25 10:14:25,613 INFO [ ome.services.util.ServiceHandler] (erver-1082) Args: ()
2015-02-25 10:14:25,614 INFO [ ome.security.basic.EventHandler] (erver-1085) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,614 INFO [ ome.security.basic.EventHandler] (erver-1082) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,614 INFO [ ome.services.util.ServiceHandler] (thread-151) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(6c5844b3-f465-43b8-a145-8e77a0059f72)
2015-02-25 10:14:25,614 INFO [ ome.services.util.ServiceHandler] (thread-151) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:25,614 INFO [ ome.services.util.ServiceHandler] (thread-152) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(6c5844b3-f465-43b8-a145-8e77a0059f72)
2015-02-25 10:14:25,614 INFO [ ome.services.util.ServiceHandler] (thread-152) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:25,615 INFO [ ome.security.basic.EventHandler] (thread-151) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:25,615 INFO [ ome.security.basic.EventHandler] (thread-152) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:25,618 INFO [ org.perf4j.TimingLogger] (thread-151) start[1424859265614] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:25,618 INFO [ ome.services.util.ServiceHandler] (thread-151) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:25,618 INFO [ org.perf4j.TimingLogger] (thread-152) start[1424859265614] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:25,618 INFO [ ome.services.util.ServiceHandler] (thread-152) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:25,618 INFO [ org.perf4j.TimingLogger] (erver-1082) start[1424859265613] time[5] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2015-02-25 10:14:25,618 INFO [ ome.services.util.ServiceHandler] (erver-1082) Rslt: ome.system.SimpleEventContext@2a76f4bb
2015-02-25 10:14:25,618 INFO [ org.perf4j.TimingLogger] (erver-1085) start[1424859265612] time[6] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2015-02-25 10:14:25,618 INFO [ ome.services.util.ServiceHandler] (erver-1085) Rslt: ome.system.SimpleEventContext@1d063d79
2015-02-25 10:14:25,619 INFO [ ome.services.util.ServiceHandler] (erver-1087) Meth: interface ome.api.ThumbnailStore.resetDefaults
2015-02-25 10:14:25,619 INFO [ ome.services.util.ServiceHandler] (erver-1087) Args: ()
2015-02-25 10:14:25,619 INFO [ ome.services.util.ServiceHandler] (erver-1083) Meth: interface ome.api.ThumbnailStore.resetDefaults
2015-02-25 10:14:25,619 INFO [ ome.services.util.ServiceHandler] (erver-1083) Args: ()
2015-02-25 10:14:25,619 INFO [ ome.security.basic.BasicEventContext] (erver-1087) cctx: group=3
2015-02-25 10:14:25,620 INFO [ ome.security.basic.BasicEventContext] (erver-1083) cctx: group=3
2015-02-25 10:14:25,623 INFO [ ome.security.basic.EventHandler] (erver-1087) Auth: user=2,group=3,event=2816849(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,623 INFO [ ome.security.basic.EventHandler] (erver-1083) Auth: user=2,group=3,event=2816850(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,623 INFO [ org.perf4j.TimingLogger] (erver-1087) start[1424859265619] time[4] tag[omero.call.exception]
2015-02-25 10:14:25,623 INFO [ ome.services.util.ServiceHandler] (erver-1087) Excp: ome.conditions.ApiUsageException: Unable to reset rendering settings in a read-only group for Pixels set id:64235
2015-02-25 10:14:25,623 INFO [ org.perf4j.TimingLogger] (erver-1083) start[1424859265619] time[3] tag[omero.call.exception]
2015-02-25 10:14:25,623 INFO [ ome.services.util.ServiceHandler] (erver-1083) Excp: ome.conditions.ApiUsageException: Unable to reset rendering settings in a read-only group for Pixels set id:73637
2015-02-25 10:14:25,640 INFO [ ome.services.util.ServiceHandler] (erver-1086) Meth: interface ome.api.StatefulServiceInterface.close
2015-02-25 10:14:25,640 INFO [ ome.services.util.ServiceHandler] (erver-1086) Args: ()
2015-02-25 10:14:25,640 INFO [ ome.security.basic.EventHandler] (erver-1086) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,641 INFO [ org.perf4j.TimingLogger] (erver-1086) start[1424859265640] time[1] tag[omero.call.success.ome.services.ThumbnailBean.close]
2015-02-25 10:14:25,641 INFO [ ome.services.util.ServiceHandler] (erver-1086) Rslt: null
2015-02-25 10:14:25,641 INFO [ ome.services.util.ServiceHandler] (erver-1086) Cleanup: ome.services.ThumbnailBean@66b10ddb
2015-02-25 10:14:25,641 INFO [ omero.cmd.SessionI] (erver-1086) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/48868f09-fbb4-4d8a-97db-83952986a605omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@a00ecb22)
2015-02-25 10:14:25,641 INFO [ ome.services.util.ServiceHandler] (erver-1084) Meth: interface ome.api.StatefulServiceInterface.close
2015-02-25 10:14:25,641 INFO [ ome.services.util.ServiceHandler] (erver-1084) Args: ()
2015-02-25 10:14:25,642 INFO [ ome.security.basic.EventHandler] (erver-1084) Auth: user=2,group=3,event=null(User),sess=6c5844b3-f465-43b8-a145-8e77a0059f72
2015-02-25 10:14:25,642 INFO [o.services.sessions.SessionContext$Count] (erver-1082) -Reference count: 6c5844b3-f465-43b8-a145-8e77a0059f72=1
2015-02-25 10:14:25,642 INFO [ omero.cmd.SessionI] (erver-1082) cleanupSelf(ServiceFactoryI(session-373b9c73-953e-4614-8cb3-75d6531d6906/6c5844b3-f465-43b8-a145-8e77a0059f72)).
2015-02-25 10:14:25,642 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/373b9c73-953e-4614-8cb3-75d6531d6906omero.api.IAdmin(omero.api._IAdminTie@810f6a24)
2015-02-25 10:14:25,642 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 373b9c73-953e-4614-8cb3-75d6531d6906omero.api.IAdmin
2015-02-25 10:14:25,642 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/373b9c73-953e-4614-8cb3-75d6531d6906omero.api.IContainer(omero.api._IContainerTie@49f0a26f)
2015-02-25 10:14:25,642 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 373b9c73-953e-4614-8cb3-75d6531d6906omero.api.IContainer
2015-02-25 10:14:25,642 INFO [ org.perf4j.TimingLogger] (erver-1084) start[1424859265641] time[0] tag[omero.call.success.ome.services.ThumbnailBean.close]
2015-02-25 10:14:25,642 INFO [ ome.services.util.ServiceHandler] (erver-1084) Rslt: null
2015-02-25 10:14:25,642 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/373b9c73-953e-4614-8cb3-75d6531d6906omero.api.IQuery(omero.api._IQueryTie@9c62d8d6)
2015-02-25 10:14:25,642 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 373b9c73-953e-4614-8cb3-75d6531d6906omero.api.IQuery
2015-02-25 10:14:25,642 INFO [ ome.services.util.ServiceHandler] (erver-1084) Cleanup: ome.services.ThumbnailBean@72999cd7
2015-02-25 10:14:25,642 INFO [ omero.cmd.SessionI] (erver-1084) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/7910e55c-376f-4eb5-9e0a-367be5d394c8omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@5a2e0761)
2015-02-25 10:14:25,643 INFO [o.services.sessions.SessionContext$Count] (erver-1082) -Reference count: 6c5844b3-f465-43b8-a145-8e77a0059f72=0
2015-02-25 10:14:25,643 INFO [ omero.cmd.SessionI] (erver-1082) cleanupSelf(ServiceFactoryI(session-21111e56-e258-438b-8239-d192aaf50282/6c5844b3-f465-43b8-a145-8e77a0059f72)).
2015-02-25 10:14:25,643 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/21111e56-e258-438b-8239-d192aaf50282omero.api.IQuery(omero.api._IQueryTie@56cd7fa1)
2015-02-25 10:14:25,643 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 21111e56-e258-438b-8239-d192aaf50282omero.api.IQuery
2015-02-25 10:14:25,643 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/21111e56-e258-438b-8239-d192aaf50282omero.api.IContainer(omero.api._IContainerTie@37a146bd)
2015-02-25 10:14:25,643 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 21111e56-e258-438b-8239-d192aaf50282omero.api.IContainer
2015-02-25 10:14:25,643 INFO [ omero.cmd.SessionI] (erver-1082) Unregistered servant:6c5844b3-f465-43b8-a145-8e77a0059f72/21111e56-e258-438b-8239-d192aaf50282omero.api.IAdmin(omero.api._IAdminTie@3622475e)
2015-02-25 10:14:25,643 INFO [ omero.cmd.SessionI] (erver-1082) Removed servant from adapter: 21111e56-e258-438b-8239-d192aaf50282omero.api.IAdmin
2015-02-25 10:14:37,982 INFO [ome.services.sessions.state.SessionCache] (2-thread-2) Synchronizing session cache. Count = 41
2015-02-25 10:14:37,982 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(6c5844b3-f465-43b8-a145-8e77a0059f72)
2015-02-25 10:14:37,982 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:37,983 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:37,986 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859277982] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:37,986 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:37,986 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(ef92ab01-6ca4-4c4d-a3f2-36ea90821e6b)
2015-02-25 10:14:37,986 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:37,987 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:37,989 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859277986] time[2] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:37,989 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:37,989 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(927ad74e-b624-4c37-94a3-2297c361937e)
2015-02-25 10:14:37,990 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:37,990 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:37,993 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859277990] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:37,993 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:37,993 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(1d62d4be-66d6-4271-a8cc-a39e72ca0daf)
2015-02-25 10:14:37,993 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:37,994 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:37,998 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859277993] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:37,998 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:37,998 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(3fb6977e-ed9c-4270-b0aa-611cc510806e)
2015-02-25 10:14:37,998 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:37,998 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,001 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859277998] time[2] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,001 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_0, ome.model.meta.ExperimenterGroup:Id_0, (0, 1, 3), ... 4 more)
2015-02-25 10:14:38,001 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(ffd69d64-4ac4-408d-afeb-07b8b4ebb421)
2015-02-25 10:14:38,001 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,001 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,003 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278001] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,003 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,003 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(80f426d3-fc17-4c71-ac44-223fe9c55c7f)
2015-02-25 10:14:38,003 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,003 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,004 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278003] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,004 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,005 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(7b8684ef-c1dd-426c-8330-a59ca6008a6e)
2015-02-25 10:14:38,005 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,005 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,006 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278005] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,006 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_0, ome.model.meta.ExperimenterGroup:Id_0, (0, 1, 3), ... 4 more)
2015-02-25 10:14:38,007 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(db0c739d-6ef5-4bc2-b115-653f116e591a)
2015-02-25 10:14:38,007 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,007 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,009 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278007] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,009 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,009 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(667a55c1-996c-4705-a06f-6c9b688afc93)
2015-02-25 10:14:38,009 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,009 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,010 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278009] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,011 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,011 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(0200fe6e-51d6-4ed0-9029-250d64003ea2)
2015-02-25 10:14:38,011 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,011 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,012 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278011] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,012 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,013 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(2aa578ce-725a-4b5d-aba7-32224666ef62)
2015-02-25 10:14:38,013 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,013 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,014 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278013] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,014 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,014 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(052e8d2f-e9f2-44a3-af5a-819a23aaf995)
2015-02-25 10:14:38,014 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,015 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,016 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278014] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,016 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,016 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(ad7fead4-d727-4eb4-b064-b742856b9864)
2015-02-25 10:14:38,016 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,017 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,018 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278016] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,018 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,018 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(4433c711-bb26-4b2e-89d2-71bc784bbb20)
2015-02-25 10:14:38,018 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,019 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,020 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278018] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,020 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,020 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(ca8f92c0-32d8-46fa-b6e2-df10189e3435)
2015-02-25 10:14:38,020 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,021 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,022 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278020] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,022 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,022 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(c35fb8c9-6b3a-454c-a16a-94d9ce8e62b3)
2015-02-25 10:14:38,022 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,023 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,025 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278022] time[2] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,025 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,025 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(c73d4a21-a6ee-48d1-b35d-516ef7c048cf)
2015-02-25 10:14:38,025 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,025 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,026 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278025] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,026 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,027 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(c117ebae-2d00-4434-b5cf-79e3fb601eea)
2015-02-25 10:14:38,027 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,027 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,028 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278027] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,028 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,028 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(b5f071ee-301a-436d-ac6d-72c666da7bdc)
2015-02-25 10:14:38,028 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,029 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,030 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278028] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,030 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,030 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(a8d2f711-2e7a-48be-b8d7-76db51b1ef8b)
2015-02-25 10:14:38,030 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,031 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,032 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278030] time[2] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,033 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,033 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(15144bac-c93f-4d47-8d96-e81649964a42)
2015-02-25 10:14:38,033 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,033 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,034 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278033] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,034 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,034 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(f1bc79ee-1db5-4465-a404-1d2b16600daa)
2015-02-25 10:14:38,035 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,035 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,036 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278035] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,036 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,036 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(263256f7-a586-47d4-9827-cc8b69b377cd)
2015-02-25 10:14:38,036 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,037 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,040 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278036] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,040 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,041 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(742e6981-8f88-412d-934d-574c6c23ad58)
2015-02-25 10:14:38,041 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,041 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,042 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278041] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,042 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,042 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(8b14e412-9aed-4f1b-9120-7f5e60484053)
2015-02-25 10:14:38,042 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,043 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,044 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278042] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,044 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,044 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(9f723e22-74e7-43a0-95e9-c9229785e92d)
2015-02-25 10:14:38,044 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,045 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,046 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278044] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,046 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,046 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(c2c6fb64-986a-4c31-8364-31dc67a52534)
2015-02-25 10:14:38,046 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,047 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,048 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278046] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,048 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,048 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(136ef9d3-20f2-48d3-a2a9-e624042302d1)
2015-02-25 10:14:38,048 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,048 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,050 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278048] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,050 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,050 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(1c1d63e6-94a9-44bd-84ba-d3b9c0835739)
2015-02-25 10:14:38,050 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,050 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,052 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278050] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,052 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,052 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(b9402086-5f5e-487f-8a9d-4fb7669237fa)
2015-02-25 10:14:38,052 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,052 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,054 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278052] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,054 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,054 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(e293c62e-038a-4d53-9505-8414b9aabbb5)
2015-02-25 10:14:38,054 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,054 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,055 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278054] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,055 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,056 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(83846982-b5b6-4dd8-9b0c-1b6a3158da8d)
2015-02-25 10:14:38,056 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,056 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,057 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278056] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,057 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,057 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(81e7741a-22dd-4ac2-bbd4-d101a290c6fb)
2015-02-25 10:14:38,058 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,058 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,059 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278058] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,059 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,060 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(f4016bcd-b253-4261-a450-9dbf00b34226)
2015-02-25 10:14:38,060 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,060 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,062 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278060] time[2] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,062 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2015-02-25 10:14:38,062 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(9fd07b5c-4263-4827-8067-a764e2da250e)
2015-02-25 10:14:38,062 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Args: [null, InternalSF@1765269412]
2015-02-25 10:14:38,062 INFO [ ome.security.basic.EventHandler] (2-thread-2) Auth: user=0,group=0,event=null(Sessions),sess=3fb6977e-ed9c-4270-b0aa-611cc510806e
2015-02-25 10:14:38,064 INFO [ org.perf4j.TimingLogger] (2-thread-2) start[1424859278062] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2015-02-25 10:14:38,064 INFO [ ome.services.util.ServiceHandler] (2-thread-2) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2

from master.err the day before - no output from today:

identity: e05db6f5-8375-4ec0-9514-b6055e8bf768/c125597b-1ad7-4f53-990e-756218717c5eomero.api.IContainer
facet:
operation: getImagesByOptions
remote host: 172.22.69.9 remote port: 58813
-! 24/02/15 11:52:05:131 warning: Blitz-0-Ice.ThreadPool.Server-6: dispatch exception:
identity: e05db6f5-8375-4ec0-9514-b6055e8bf768/c125597b-1ad7-4f53-990e-756218717c5eomero.api.IContainer
facet:
operation: getImagesByOptions
remote host: 10.3.2.207 remote port: 59498
Ice.MemoryLimitException
reason = "requested 67108868 bytes, maximum allowed is 67108864 bytes (see Ice.MessageSizeMax)"
at IceInternal.Ex.throwMemoryLimitException(Ex.java:34)
at IceInternal.BasicStream.expand(BasicStream.java:2515)
at IceInternal.BasicStream.writeInt(BasicStream.java:1208)
at IceInternal.BasicStream$EncapsEncoder10.startSlice(BasicStream.java:4058)
at IceInternal.BasicStream.startWriteSlice(BasicStream.java:548)
at omero.RInt.__writeImpl(RInt.java:150)
at Ice.ObjectImpl.__write(ObjectImpl.java:357)
at IceInternal.BasicStream$EncapsEncoder10.writePendingObjects(BasicStream.java:4106)
at IceInternal.BasicStream.writePendingObjects(BasicStream.java:606)
at omero.api._AMD_IContainer_getImagesByOptions.ice_response(_AMD_IContainer_getImagesByOptions.java:38)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at ome.services.throttling.Task.response(Task.java:63)
at ome.services.throttling.Callback.run(Callback.java:57)
at ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)
at ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:149)
at ome.services.blitz.impl.ContainerI.getImagesByOptions_async(ContainerI.java:138)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at omero.cmd.CallContext.invoke(CallContext.java:78)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy81.getImagesByOptions_async(Unknown Source)
at omero.api._IContainerTie.getImagesByOptions_async(_IContainerTie.java:112)
at omero.api._IContainerDisp.___getImagesByOptions(_IContainerDisp.java:284)
at omero.api._IContainerDisp.__dispatch(_IContainerDisp.java:608)
at IceInternal.Incoming.invoke(Incoming.java:222)
at Ice.ConnectionI.invokeAll(ConnectionI.java:2482)
at Ice.ConnectionI.dispatch(ConnectionI.java:1258)
at Ice.ConnectionI.message(ConnectionI.java:1213)
at IceInternal.ThreadPool.run(ThreadPool.java:321)
at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:693)
at java.lang.Thread.run(Thread.java:701)

-! 24/02/15 11:52:44:924 warning: Blitz-0-Ice.ThreadPool.Server-5: dispatch exception:
identity: e05db6f5-8375-4ec0-9514-b6055e8bf768/c125597b-1ad7-4f53-990e-756218717c5eomero.api.IContainer
facet:
operation: getImagesByOptions
remote host: 10.3.2.207 remote port: 59498
Ice.MemoryLimitException
reason = "requested 67108893 bytes, maximum allowed is 67108864 bytes (see Ice.MessageSizeMax)"
at IceInternal.Ex.throwMemoryLimitException(Ex.java:34)
at IceInternal.BasicStream.expand(BasicStream.java:2515)
at IceInternal.BasicStream.writeString(BasicStream.java:1743)
at Ice.ContextHelper.write(ContextHelper.java:38)
at omero.model.Details.__writeImpl(Details.java:408)
at Ice.ObjectImpl.__write(ObjectImpl.java:357)
at IceInternal.BasicStream$EncapsEncoder10.writePendingObjects(BasicStream.java:4106)
at IceInternal.BasicStream.writePendingObjects(BasicStream.java:606)
at omero.api._AMD_IContainer_getImagesByOptions.ice_response(_AMD_IContainer_getImagesByOptions.java:38)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at ome.services.throttling.Task.response(Task.java:63)
at ome.services.throttling.Callback.run(Callback.java:57)
at ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)
at ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:149)
at ome.services.blitz.impl.ContainerI.getImagesByOptions_async(ContainerI.java:138)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at omero.cmd.CallContext.invoke(CallContext.java:78)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy81.getImagesByOptions_async(Unknown Source)
at omero.api._IContainerTie.getImagesByOptions_async(_IContainerTie.java:112)
at omero.api._IContainerDisp.___getImagesByOptions(_IContainerDisp.java:284)
at omero.api._IContainerDisp.__dispatch(_IContainerDisp.java:608)
at IceInternal.Incoming.invoke(Incoming.java:222)
at Ice.ConnectionI.invokeAll(ConnectionI.java:2482)
at Ice.ConnectionI.dispatch(ConnectionI.java:1258)
at Ice.ConnectionI.message(ConnectionI.java:1213)
at IceInternal.ThreadPool.run(ThreadPool.java:321)
at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:693)
at java.lang.Thread.run(Thread.java:701)

-! 02/24/15 11:52:44.927 OMERO.Glacier2: warning: dispatch exception: OutgoingAsync.cpp:720: Ice::UnknownLocalException:
unknown local exception:
Ice::MemoryLimitException
Ice.MemoryLimitException
reason = "requested 67108893 bytes, maximum allowed is 67108864 bytes (see Ice.MessageSizeMax)"
at IceInternal.Ex.throwMemoryLimitException(Ex.java:34)
at IceInternal.BasicStream.expand(BasicStream.java:2515)
at IceInternal.BasicStream.writeString(BasicStream.java:1743)
at Ice.ContextHelper.write(ContextHelper.java:38)
at omero.model.Details.__writeImpl(Details.java:408)
at Ice.ObjectImpl.__write(ObjectImpl.java:357)
at IceInternal.BasicStream$EncapsEncoder10.writePendingObjects(BasicStream.java:4106)
at IceInternal.BasicStream.writePendingObjects(BasicStream.java:606)
at omero.api._AMD_IContainer_getImagesByOptions.ice_response(_AMD_IContainer_getImagesByOptions.java:38)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at ome.services.throttling.Task.response(Task.java:63)
at ome.services.throttling.Callback.run(Callback.java:57)
at ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)
at ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:149)
at ome.services.blitz.impl.ContainerI.getImagesByOptions_async(ContainerI.java:138)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at omero.cmd.CallContext.invoke(CallContext.java:78)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy81.getImagesByOptions_async(Unknown Source)
at omero.api._IContainerTie.getImagesByOptions_async(_IContainerTie.java:112)
at omero.api._IContainerDisp.___getImagesByOptions(_IContainerDisp.java:284)
at omero.api._IContainerDisp.__dispatch(_IContainerDisp.java:608)
at IceInternal.Incoming.invoke(Incoming.java:222)
at Ice.ConnectionI.invokeAll(ConnectionI.java:2482)
at Ice.ConnectionI.dispatch(ConnectionI.java:1258)
at Ice.ConnectionI.message(ConnectionI.java:1213)
at IceInternal.ThreadPool.run(ThreadPool.java:321)
at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:693)
at java.lang.Thread.run(Thread.java:701)

If I click on the render_image webgateway url I get this exception in the OMEROweb.log - but when I wait a few seconds after this request, then request it again it's there with no error as it's created the pyramid:



2015-02-25 11:17:13,101 WARNI [ omero.gateway] (proc.14273) debug:3617 MissingPyramidException on <class 'omeroweb.webclient.webclient_gateway.OmeroWebSafeCallWrapper'> to <7818475d-54db-4096-9991-d4d9c960330bomero.api.RenderingEngine> resetDefaults((<ServiceOptsDict: {'omero.session.uuid': '89d51ca6-84ef-438c-ab76-54cc15636a6c', 'omero.group': '3', 'omero.client.uuid': 'c3a88fe9-c350-4f97-bc59-14a9ba659182'}>,), {})
Traceback (most recent call last):
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero/gateway/__init__.py", line 3635, in __call__
return self.f(*args, **kwargs)
File "/net/isilonP/public/rw/homes/mi_adm/omero/OMERO.server-5.0.4-ice35-b43/lib/python/omero_api_RenderingEngine_ice.py", line 776, in resetDefaults
return _M_omero.api.RenderingEngine._op_resetDefaults.invoke(self, ((), _ctx))
MissingPyramidException: exception ::omero::MissingPyramidException
{
serverStackTrace = ome.conditions.MissingPyramidException: Missing pyramid:/nfs/komp2/web/omero/omero_data/OMERO/Pixels/Dir-040/40362_pyramid
at ome.io.nio.SimpleBackOff.throwMissingPyramidException(SimpleBackOff.java:82)
at ome.io.nio.PixelsService.handleMissingPyramid(PixelsService.java:726)
at ome.io.nio.PixelsService._getPixelBuffer(PixelsService.java:537)
at ome.io.nio.PixelsService.getPixelBuffer(PixelsService.java:495)
at ome.logic.RenderingSettingsImpl.resetDefaults(RenderingSettingsImpl.java:500)
at ome.logic.RenderingSettingsImpl.resetDefaults(RenderingSettingsImpl.java:1324)
at ome.services.RenderingBean$11.doWork(RenderingBean.java:1904)
at sun.reflect.GeneratedMethodAccessor253.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at ome.services.util.Executor$Impl$Interceptor.invoke(Executor.java:576)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.security.basic.EventHandler.invoke(EventHandler.java:154)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:241)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy66.doWork(Unknown Source)
at ome.services.util.Executor$Impl.execute(Executor.java:457)
at ome.services.util.Executor$Impl.execute(Executor.java:401)
at ome.services.RenderingBean._resetDefaults(RenderingBean.java:1901)
at ome.services.RenderingBean.internalReset(RenderingBean.java:735)
at ome.services.RenderingBean.resetDefaults(RenderingBean.java:678)
at sun.reflect.GeneratedMethodAccessor801.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy88.resetDefaults(Unknown Source)
at sun.reflect.GeneratedMethodAccessor801.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at ome.security.basic.BasicSecurityWiring.invoke(BasicSecurityWiring.java:98)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at ome.services.blitz.fire.AopContextInitializer.invoke(AopContextInitializer.java:43)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy88.resetDefaults(Unknown Source)
at sun.reflect.GeneratedMethodAccessor931.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at ome.services.blitz.util.IceMethodInvoker.invoke(IceMethodInvoker.java:179)
at ome.services.throttling.Callback.run(Callback.java:56)
at ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)
at ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:149)
at ome.services.blitz.impl.RenderingEngineI.resetDefaults_async(RenderingEngineI.java:378)
at sun.reflect.GeneratedMethodAccessor930.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:622)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at omero.cmd.CallContext.invoke(CallContext.java:78)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at com.sun.proxy.$Proxy90.resetDefaults_async(Unknown Source)
at omero.api._RenderingEngineTie.resetDefaults_async(_RenderingEngineTie.java:298)
at omero.api._RenderingEngineDisp.___resetDefaults(_RenderingEngineDisp.java:1320)
at omero.api._RenderingEngineDisp.__dispatch(_RenderingEngineDisp.java:1714)
at IceInternal.Incoming.invoke(Incoming.java:222)
at Ice.ConnectionI.invokeAll(ConnectionI.java:2482)
at Ice.ConnectionI.dispatch(ConnectionI.java:1258)
at Ice.ConnectionI.message(ConnectionI.java:1213)
at IceInternal.ThreadPool.run(ThreadPool.java:321)
at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:693)
at java.lang.Thread.run(Thread.java:701)

serverExceptionClass = ome.conditions.MissingPyramidException
message = Missing pyramid:/nfs/komp2/web/omero/omero_data/OMERO/Pixels/Dir-040/40362_pyramid
backOff = 50515
pixelsID = 40362
}

Ah, MissingPyramidException isn't a fatal exception. It extends from ConcurrencyException which implies you should be able to retry the call at some point. What code is receiving this exception?

Cheers,
~Josh.

Hi Josh

Just occurs in the logs when trying to access an image via the webgateway detail view.

Jonathan,

how big is each TIFF? If these are "big images" i.e. over 3000 x 3000, then they're going to need a pyramid generated for them. One suggestion would be to:

• check var/log/PixelData-0.log and make sure it's not working on anything
• upload a single one of these images with --no_thumbnail
• see if PixelData-0.log has started working (I'd think it *won't* have)
• try to access the URL and confirm that you get a MissingPyramidException
• check that now the generation of the thumbnails has begun
• see how long the calculation takes
• re-try to access the URL and see if you get thumbnails.

Cheers,
~Josh.

Hi Josh

OK - so there are at least two things going on here 1) thumbnails not showing if not accessed the render_image url before (occurs with any sized image) and 2) pyramids not created for some of the larger images.

To get over these I've written a python crawler to request the render_image urls so the thumbnails and if necessary pyramids are created and stored. This appears to be having the desired effect so I'm not going to spend time right now tracing any bug.

So the waiting for pyramid to load is the standard behaviour for the larger images? So my crawler will be useful in future?

Thanks

Jonathan