2014-06-12 14:02:21,248 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest)
2014-06-12 14:02:21,248 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:21,251 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:	user=0,group=0,event=null(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:21,253 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1402578141248] time[5] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2014-06-12 14:02:21,253 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	true
2014-06-12 14:02:21,255 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession
2014-06-12 14:02:21,255 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:21,272 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:	user=0,group=0,event=132750(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:21,295 INFO  [       ome.security.basic.CurrentDetails] (l.Server-1) Adding log:INSERT,class ome.model.meta.Session,7921
2014-06-12 14:02:21,324 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1402578141255] time[69] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
2014-06-12 14:02:21,324 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:	(ome.model.meta.Experimenter:Id_1, ome.model.meta.ExperimenterGroup:Id_2, (2), ... 4 more)
2014-06-12 14:02:21,326 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-1) Created session ServiceFactoryI(session-17fbad3e-4a3b-4b3a-afba-5a4c2097bc9c/6bb31ab5-938c-408e-95a4-39609a88eee9) for user guest (agent=OMERO.web)
2014-06-12 14:02:21,343 INFO  [                      omero.cmd.SessionI] (l.Server-0) Added servant to adapter: 6bb31ab5-938c-408e-95a4-39609a88eee9/17fbad3e-4a3b-4b3a-afba-5a4c2097bc9comero.api.ISession(omero.api._ISessionTie@5a6a4deb)
2014-06-12 14:02:21,723 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.ISession.getSession
2014-06-12 14:02:21,724 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[6bb31ab5-938c-408e-95a4-39609a88eee9]
2014-06-12 14:02:21,724 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1402578141724] time[0] tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
2014-06-12 14:02:21,724 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	ome.model.meta.Session:Id_7921
2014-06-12 14:02:21,733 INFO  [                      omero.cmd.SessionI] (l.Server-3) Added servant to adapter: 6bb31ab5-938c-408e-95a4-39609a88eee9/17fbad3e-4a3b-4b3a-afba-5a4c2097bc9comero.api.IConfig(omero.api._IConfigTie@b0196453)
2014-06-12 14:02:21,735 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.IConfig.getConfigValue
2014-06-12 14:02:21,735 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[omero.router.insecure]
2014-06-12 14:02:21,749 INFO  [         ome.security.basic.EventHandler] (l.Server-3)  Auth:	user=1,group=2,event=132751(User),sess=6bb31ab5-938c-408e-95a4-39609a88eee9
2014-06-12 14:02:21,754 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1402578141735] time[19] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
2014-06-12 14:02:21,755 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	OMERO.Glacier2/router:tcp -p 4063 -h @omero.host@
2014-06-12 14:02:21,759 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-5) Found session locally: 6bb31ab5-938c-408e-95a4-39609a88eee9
2014-06-12 14:02:21,760 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-5) Rejoining session ServiceFactoryI(session-5d52e864-1068-48cb-883d-3204858c04a6/6bb31ab5-938c-408e-95a4-39609a88eee9) (agent=OMERO.web;secure=False)
2014-06-12 14:02:21,762 INFO  [e.services.sessions.SessionContext$Count] (l.Server-2) -Reference count: 6bb31ab5-938c-408e-95a4-39609a88eee9=1
2014-06-12 14:02:21,763 INFO  [                      omero.cmd.SessionI] (l.Server-2) cleanupSelf(ServiceFactoryI(session-17fbad3e-4a3b-4b3a-afba-5a4c2097bc9c/6bb31ab5-938c-408e-95a4-39609a88eee9)).
2014-06-12 14:02:21,763 INFO  [                      omero.cmd.SessionI] (l.Server-2) Unregistered servant:6bb31ab5-938c-408e-95a4-39609a88eee9/17fbad3e-4a3b-4b3a-afba-5a4c2097bc9comero.api.ISession(omero.api._ISessionTie@5a6a4deb)
2014-06-12 14:02:21,763 INFO  [                      omero.cmd.SessionI] (l.Server-2) Removed servant from adapter: 17fbad3e-4a3b-4b3a-afba-5a4c2097bc9comero.api.ISession
2014-06-12 14:02:21,764 INFO  [                      omero.cmd.SessionI] (l.Server-2) Unregistered servant:6bb31ab5-938c-408e-95a4-39609a88eee9/17fbad3e-4a3b-4b3a-afba-5a4c2097bc9comero.api.IConfig(omero.api._IConfigTie@b0196453)
2014-06-12 14:02:21,764 INFO  [                      omero.cmd.SessionI] (l.Server-2) Removed servant from adapter: 17fbad3e-4a3b-4b3a-afba-5a4c2097bc9comero.api.IConfig
2014-06-12 14:02:21,769 INFO  [                      omero.cmd.SessionI] (l.Server-2) Added servant to adapter: 6bb31ab5-938c-408e-95a4-39609a88eee9/5d52e864-1068-48cb-883d-3204858c04a6omero.api.IConfig(omero.api._IConfigTie@61ff9760)
2014-06-12 14:02:21,770 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Meth:	interface ome.api.IConfig.getConfigValue
2014-06-12 14:02:21,770 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	[omero.version]
2014-06-12 14:02:21,796 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:	user=1,group=2,event=132752(User),sess=6bb31ab5-938c-408e-95a4-39609a88eee9
2014-06-12 14:02:21,800 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1402578141770] time[30] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
2014-06-12 14:02:21,800 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	4.4.8p1-ice33-b304
2014-06-12 14:02:21,815 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(shared)
2014-06-12 14:02:21,815 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:21,817 INFO  [         ome.security.basic.EventHandler] (l.Server-4)  Auth:	user=0,group=0,event=null(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:21,819 INFO  [                 org.perf4j.TimingLogger] (l.Server-4) start[1402578141815] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2014-06-12 14:02:21,819 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Rslt:	true
2014-06-12 14:02:21,820 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession
2014-06-12 14:02:21,820 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:21,834 INFO  [         ome.security.basic.EventHandler] (l.Server-7)  Auth:	user=0,group=0,event=132753(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:21,867 INFO  [       ome.security.basic.CurrentDetails] (l.Server-7) Adding log:INSERT,class ome.model.meta.Session,7922
2014-06-12 14:02:21,888 INFO  [                 org.perf4j.TimingLogger] (l.Server-7) start[1402578141820] time[68] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
2014-06-12 14:02:21,888 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Rslt:	(ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2014-06-12 14:02:21,890 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-7) Created session ServiceFactoryI(session-96adff46-eddf-4a3b-84c7-6fb811cf2761/5a1dc603-b2de-4dfc-99a6-6fe724e2521d) for user shared (agent=OMERO.web)
2014-06-12 14:02:21,896 INFO  [                      omero.cmd.SessionI] (l.Server-3) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/96adff46-eddf-4a3b-84c7-6fb811cf2761omero.api.ISession(omero.api._ISessionTie@7176011c)
2014-06-12 14:02:21,897 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.ISession.getSession
2014-06-12 14:02:21,897 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[5a1dc603-b2de-4dfc-99a6-6fe724e2521d]
2014-06-12 14:02:21,898 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1402578141897] time[1] tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
2014-06-12 14:02:21,898 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	ome.model.meta.Session:Id_7922
2014-06-12 14:02:21,908 INFO  [                      omero.cmd.SessionI] (l.Server-3) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/96adff46-eddf-4a3b-84c7-6fb811cf2761omero.api.IConfig(omero.api._IConfigTie@a504d274)
2014-06-12 14:02:21,910 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Meth:	interface ome.api.IConfig.getConfigValue
2014-06-12 14:02:21,910 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Args:	[omero.router.insecure]
2014-06-12 14:02:21,939 INFO  [         ome.security.basic.EventHandler] (l.Server-8)  Auth:	user=2,group=3,event=132754(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:21,944 INFO  [                 org.perf4j.TimingLogger] (l.Server-8) start[1402578141910] time[34] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
2014-06-12 14:02:21,944 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Rslt:	OMERO.Glacier2/router:tcp -p 4063 -h @omero.host@
2014-06-12 14:02:21,949 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-0) Found session locally: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:21,950 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-0) Rejoining session ServiceFactoryI(session-109927c3-b87d-46a7-88f0-c7805e88c9d1/5a1dc603-b2de-4dfc-99a6-6fe724e2521d) (agent=OMERO.web;secure=False)
2014-06-12 14:02:21,952 INFO  [e.services.sessions.SessionContext$Count] (l.Server-4) -Reference count: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d=1
2014-06-12 14:02:21,952 INFO  [                      omero.cmd.SessionI] (l.Server-4) cleanupSelf(ServiceFactoryI(session-96adff46-eddf-4a3b-84c7-6fb811cf2761/5a1dc603-b2de-4dfc-99a6-6fe724e2521d)).
2014-06-12 14:02:21,952 INFO  [                      omero.cmd.SessionI] (l.Server-4) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/96adff46-eddf-4a3b-84c7-6fb811cf2761omero.api.ISession(omero.api._ISessionTie@7176011c)
2014-06-12 14:02:21,952 INFO  [                      omero.cmd.SessionI] (l.Server-4) Removed servant from adapter: 96adff46-eddf-4a3b-84c7-6fb811cf2761omero.api.ISession
2014-06-12 14:02:21,953 INFO  [                      omero.cmd.SessionI] (l.Server-4) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/96adff46-eddf-4a3b-84c7-6fb811cf2761omero.api.IConfig(omero.api._IConfigTie@a504d274)
2014-06-12 14:02:21,953 INFO  [                      omero.cmd.SessionI] (l.Server-4) Removed servant from adapter: 96adff46-eddf-4a3b-84c7-6fb811cf2761omero.api.IConfig
2014-06-12 14:02:21,959 INFO  [                      omero.cmd.SessionI] (l.Server-4) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/109927c3-b87d-46a7-88f0-c7805e88c9d1omero.api.IAdmin(omero.api._IAdminTie@73c1fdc4)
2014-06-12 14:02:21,960 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Meth:	interface ome.api.IAdmin.getEventContext
2014-06-12 14:02:21,960 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:	()
2014-06-12 14:02:21,963 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:21,963 INFO  [        ome.services.util.ServiceHandler] (2-thread-5)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(5a1dc603-b2de-4dfc-99a6-6fe724e2521d)
2014-06-12 14:02:21,963 INFO  [        ome.services.util.ServiceHandler] (2-thread-5)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:21,965 INFO  [         ome.security.basic.EventHandler] (2-thread-5)  Auth:	user=0,group=0,event=null(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:21,972 INFO  [                 org.perf4j.TimingLogger] (2-thread-5) start[1402578141963] time[9] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2014-06-12 14:02:21,972 INFO  [        ome.services.util.ServiceHandler] (2-thread-5)  Rslt:	(ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2014-06-12 14:02:21,973 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1402578141960] time[13] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2014-06-12 14:02:21,973 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:	ome.system.SimpleEventContext@37154ecd
2014-06-12 14:02:21,975 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Meth:	interface ome.api.IAdmin.getSecurityRoles
2014-06-12 14:02:21,975 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:	()
2014-06-12 14:02:21,977 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:21,978 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1402578141975] time[3] tag[omero.call.success.ome.logic.AdminImpl.getSecurityRoles]
2014-06-12 14:02:21,985 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:	ome.system.Roles@67d1de8b
2014-06-12 14:02:22,019 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-3) Found session locally: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,020 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-3) Rejoining session ServiceFactoryI(session-ed05c801-003c-49e4-9fb9-9d3353032ed1/5a1dc603-b2de-4dfc-99a6-6fe724e2521d) (agent=OMERO.web)
2014-06-12 14:02:22,028 INFO  [                      omero.cmd.SessionI] (l.Server-2) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/ed05c801-003c-49e4-9fb9-9d3353032ed1omero.api.IAdmin(omero.api._IAdminTie@a9ac8ac7)
2014-06-12 14:02:22,029 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Meth:	interface ome.api.IAdmin.getEventContext
2014-06-12 14:02:22,029 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	()
2014-06-12 14:02:22,032 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,032 INFO  [        ome.services.util.ServiceHandler] (2-thread-1)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(5a1dc603-b2de-4dfc-99a6-6fe724e2521d)
2014-06-12 14:02:22,032 INFO  [        ome.services.util.ServiceHandler] (2-thread-1)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:22,034 INFO  [         ome.security.basic.EventHandler] (2-thread-1)  Auth:	user=0,group=0,event=null(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:22,052 INFO  [                 org.perf4j.TimingLogger] (2-thread-1) start[1402578142032] time[19] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2014-06-12 14:02:22,052 INFO  [        ome.services.util.ServiceHandler] (2-thread-1)  Rslt:	(ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2014-06-12 14:02:22,053 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1402578142029] time[24] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2014-06-12 14:02:22,053 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	ome.system.SimpleEventContext@38e23e33
2014-06-12 14:02:22,057 INFO  [                      omero.cmd.SessionI] (l.Server-5) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/ed05c801-003c-49e4-9fb9-9d3353032ed1omero.api.IQuery(omero.api._IQueryTie@6ea43594)
2014-06-12 14:02:22,058 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface ome.api.IQuery.findByQuery
2014-06-12 14:02:22,058 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	[select distinct obj from ExperimenterGroup as obj left outer join fetch obj.groupExperimenterMap as map left outer join fetch map.child e where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
2014-06-12 14:02:22,060 INFO  [    ome.security.basic.BasicEventContext] (l.Server-5)  cctx:	group=-1
2014-06-12 14:02:22,063 INFO  [         ome.security.basic.EventHandler] (l.Server-5)  Auth:	user=2,group=-1,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,069 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1402578142059] time[10] tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2014-06-12 14:02:22,069 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	ome.model.meta.ExperimenterGroup:Id_3
2014-06-12 14:02:22,084 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Meth:	interface ome.api.IQuery.findAllByQuery
2014-06-12 14:02:22,084 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:	[select e from ExperimenterGroup as e where e.id in (:ids), PARAMS:ids=ArrayList(2) ]
2014-06-12 14:02:22,085 INFO  [    ome.security.basic.BasicEventContext] (l.Server-1)  cctx:	group=-1
2014-06-12 14:02:22,089 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:	user=2,group=-1,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,093 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1402578142084] time[9] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2014-06-12 14:02:22,093 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:	(ome.model.meta.ExperimenterGroup:Id_1, ome.model.meta.ExperimenterGroup:Id_3)
2014-06-12 14:02:22,102 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Meth:	interface ome.api.IQuery.findByQuery
2014-06-12 14:02:22,102 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:	[select distinct obj from ExperimenterGroup as obj left outer join fetch obj.groupExperimenterMap as map left outer join fetch map.child e where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
2014-06-12 14:02:22,103 INFO  [    ome.security.basic.BasicEventContext] (l.Server-9)  cctx:	group=-1
2014-06-12 14:02:22,107 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:	user=2,group=-1,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,112 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1402578142102] time[10] tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2014-06-12 14:02:22,112 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:	ome.model.meta.ExperimenterGroup:Id_3
2014-06-12 14:02:22,120 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Meth:	interface ome.api.IQuery.findByQuery
2014-06-12 14:02:22,120 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Args:	[select distinct obj from ExperimenterGroup as obj left outer join fetch obj.groupExperimenterMap as map left outer join fetch map.child e where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
2014-06-12 14:02:22,121 INFO  [    ome.security.basic.BasicEventContext] (l.Server-8)  cctx:	group=-1
2014-06-12 14:02:22,125 INFO  [         ome.security.basic.EventHandler] (l.Server-8)  Auth:	user=2,group=-1,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,130 INFO  [                 org.perf4j.TimingLogger] (l.Server-8) start[1402578142120] time[10] tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2014-06-12 14:02:22,130 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Rslt:	ome.model.meta.ExperimenterGroup:Id_3
2014-06-12 14:02:22,143 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.IQuery.findByQuery
2014-06-12 14:02:22,143 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[select distinct obj from Experimenter as obj left outer join fetch obj.groupExperimenterMap as map left outer join fetch map.parent g where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
2014-06-12 14:02:22,144 INFO  [    ome.security.basic.BasicEventContext] (l.Server-3)  cctx:	group=-1
2014-06-12 14:02:22,148 INFO  [         ome.security.basic.EventHandler] (l.Server-3)  Auth:	user=2,group=-1,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,157 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1402578142143] time[14] tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2014-06-12 14:02:22,157 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	ome.model.meta.Experimenter:Id_2
2014-06-12 14:02:22,217 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Meth:	interface ome.api.IQuery.findByQuery
2014-06-12 14:02:22,217 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Args:	[select distinct obj from Experimenter as obj left outer join fetch obj.groupExperimenterMap as map left outer join fetch map.parent g where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
2014-06-12 14:02:22,218 INFO  [    ome.security.basic.BasicEventContext] (l.Server-0)  cctx:	group=-1
2014-06-12 14:02:22,222 INFO  [         ome.security.basic.EventHandler] (l.Server-0)  Auth:	user=2,group=-1,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,228 INFO  [                 org.perf4j.TimingLogger] (l.Server-0) start[1402578142217] time[11] tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2014-06-12 14:02:22,228 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Rslt:	ome.model.meta.Experimenter:Id_2
2014-06-12 14:02:22,252 INFO  [e.services.sessions.SessionContext$Count] (l.Server-7) -Reference count: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d=1
2014-06-12 14:02:22,252 INFO  [                      omero.cmd.SessionI] (l.Server-7) cleanupSelf(ServiceFactoryI(session-ed05c801-003c-49e4-9fb9-9d3353032ed1/5a1dc603-b2de-4dfc-99a6-6fe724e2521d)).
2014-06-12 14:02:22,252 INFO  [                      omero.cmd.SessionI] (l.Server-7) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/ed05c801-003c-49e4-9fb9-9d3353032ed1omero.api.IAdmin(omero.api._IAdminTie@a9ac8ac7)
2014-06-12 14:02:22,253 INFO  [                      omero.cmd.SessionI] (l.Server-7) Removed servant from adapter: ed05c801-003c-49e4-9fb9-9d3353032ed1omero.api.IAdmin
2014-06-12 14:02:22,253 INFO  [                      omero.cmd.SessionI] (l.Server-7) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/ed05c801-003c-49e4-9fb9-9d3353032ed1omero.api.IQuery(omero.api._IQueryTie@6ea43594)
2014-06-12 14:02:22,253 INFO  [                      omero.cmd.SessionI] (l.Server-7) Removed servant from adapter: ed05c801-003c-49e4-9fb9-9d3353032ed1omero.api.IQuery
2014-06-12 14:02:22,397 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-6) Found session locally: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,398 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-6) Rejoining session ServiceFactoryI(session-41caf55e-8c14-431e-be67-64e2725698fd/5a1dc603-b2de-4dfc-99a6-6fe724e2521d) (agent=OMERO.web)
2014-06-12 14:02:22,405 INFO  [                      omero.cmd.SessionI] (l.Server-4) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/41caf55e-8c14-431e-be67-64e2725698fdomero.api.IMetadata(omero.api._IMetadataTie@6c7f99f9)
2014-06-12 14:02:22,409 INFO  [                      omero.cmd.SessionI] (l.Server-4) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/41caf55e-8c14-431e-be67-64e2725698fdomero.api.IAdmin(omero.api._IAdminTie@491f9bc0)
2014-06-12 14:02:22,411 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Meth:	interface ome.api.IAdmin.getEventContext
2014-06-12 14:02:22,411 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Args:	()
2014-06-12 14:02:22,413 INFO  [         ome.security.basic.EventHandler] (l.Server-8)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,414 INFO  [        ome.services.util.ServiceHandler] (2-thread-2)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(5a1dc603-b2de-4dfc-99a6-6fe724e2521d)
2014-06-12 14:02:22,414 INFO  [        ome.services.util.ServiceHandler] (2-thread-2)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:22,416 INFO  [         ome.security.basic.EventHandler] (2-thread-2)  Auth:	user=0,group=0,event=null(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:22,437 INFO  [                 org.perf4j.TimingLogger] (2-thread-2) start[1402578142414] time[23] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2014-06-12 14:02:22,437 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)
2014-06-12 14:02:22,439 INFO  [                 org.perf4j.TimingLogger] (l.Server-8) start[1402578142411] time[28] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2014-06-12 14:02:22,439 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Rslt:	ome.system.SimpleEventContext@5ecadecc
2014-06-12 14:02:22,441 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.IMetadata.loadAnnotations
2014-06-12 14:02:22,442 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[class ome.model.meta.Experimenter, (2), (), (), null]
2014-06-12 14:02:22,446 INFO  [         ome.security.basic.EventHandler] (l.Server-3)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,457 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1402578142442] time[15] tag[omero.call.success.ome.logic.MetadataImpl.loadAnnotations]
2014-06-12 14:02:22,457 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	{2=()}
2014-06-12 14:02:22,485 INFO  [e.services.sessions.SessionContext$Count] (l.Server-0) -Reference count: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d=1
2014-06-12 14:02:22,485 INFO  [                      omero.cmd.SessionI] (l.Server-0) cleanupSelf(ServiceFactoryI(session-41caf55e-8c14-431e-be67-64e2725698fd/5a1dc603-b2de-4dfc-99a6-6fe724e2521d)).
2014-06-12 14:02:22,485 INFO  [                      omero.cmd.SessionI] (l.Server-0) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/41caf55e-8c14-431e-be67-64e2725698fdomero.api.IAdmin(omero.api._IAdminTie@491f9bc0)
2014-06-12 14:02:22,485 INFO  [                      omero.cmd.SessionI] (l.Server-0) Removed servant from adapter: 41caf55e-8c14-431e-be67-64e2725698fdomero.api.IAdmin
2014-06-12 14:02:22,486 INFO  [                      omero.cmd.SessionI] (l.Server-0) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/41caf55e-8c14-431e-be67-64e2725698fdomero.api.IMetadata(omero.api._IMetadataTie@6c7f99f9)
2014-06-12 14:02:22,486 INFO  [                      omero.cmd.SessionI] (l.Server-0) Removed servant from adapter: 41caf55e-8c14-431e-be67-64e2725698fdomero.api.IMetadata
2014-06-12 14:02:22,495 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-0) Found session locally: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,497 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-0) Rejoining session ServiceFactoryI(session-7be4eb97-09d9-4d4e-a32a-5f09e8d93c37/5a1dc603-b2de-4dfc-99a6-6fe724e2521d) (agent=OMERO.web)
2014-06-12 14:02:22,505 INFO  [                      omero.cmd.SessionI] (l.Server-6) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IAdmin(omero.api._IAdminTie@7bc78b07)
2014-06-12 14:02:22,509 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Meth:	interface ome.api.IAdmin.getEventContext
2014-06-12 14:02:22,509 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:	()
2014-06-12 14:02:22,512 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,512 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(5a1dc603-b2de-4dfc-99a6-6fe724e2521d)
2014-06-12 14:02:22,512 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:22,514 INFO  [         ome.security.basic.EventHandler] (2-thread-3)  Auth:	user=0,group=0,event=null(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:22,521 INFO  [                 org.perf4j.TimingLogger] (2-thread-3) start[1402578142512] time[9] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2014-06-12 14:02:22,522 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Rslt:	(ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2014-06-12 14:02:22,523 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1402578142510] time[13] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2014-06-12 14:02:22,523 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:	ome.system.SimpleEventContext@2490e4e7
2014-06-12 14:02:22,527 INFO  [                      omero.cmd.SessionI] (l.Server-4) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IQuery(omero.api._IQueryTie@a4206d46)
2014-06-12 14:02:22,528 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Meth:	interface ome.api.IQuery.findByQuery
2014-06-12 14:02:22,529 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Args:	[select distinct obj from Experimenter as obj left outer join fetch obj.groupExperimenterMap as map left outer join fetch map.parent g where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
2014-06-12 14:02:22,531 INFO  [    ome.security.basic.BasicEventContext] (l.Server-4)  cctx:	group=3
2014-06-12 14:02:22,531 INFO  [         ome.security.basic.EventHandler] (l.Server-4)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,536 INFO  [                 org.perf4j.TimingLogger] (l.Server-4) start[1402578142529] time[7] tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2014-06-12 14:02:22,538 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Rslt:	ome.model.meta.Experimenter:Id_2
2014-06-12 14:02:22,542 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.IQuery.findAllByQuery
2014-06-12 14:02:22,543 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[select obj from Project obj join fetch obj.details.owner as owner join fetch obj.details.group join fetch obj.details.creationEvent where owner.id = (:eid), PARAMS:F(u2):eid=2 ]
2014-06-12 14:02:22,545 INFO  [    ome.security.basic.BasicEventContext] (l.Server-3)  cctx:	group=3
2014-06-12 14:02:22,545 INFO  [         ome.security.basic.EventHandler] (l.Server-3)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,551 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1402578142543] time[8] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2014-06-12 14:02:22,552 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	(ome.model.containers.Project:Id_301)
2014-06-12 14:02:22,557 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Meth:	interface ome.api.IQuery.findAllByQuery
2014-06-12 14:02:22,557 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Args:	[select obj from Dataset as obj join fetch obj.details.creationEvent join fetch obj.details.owner join fetch obj.details.group where obj.details.owner.id=:eid and not exists (select obl from ProjectDatasetLink as obl where obl.child=obj.id), PARAMS:eid=2 ]
2014-06-12 14:02:22,559 INFO  [    ome.security.basic.BasicEventContext] (l.Server-7)  cctx:	group=3
2014-06-12 14:02:22,559 INFO  [         ome.security.basic.EventHandler] (l.Server-7)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,563 INFO  [                 org.perf4j.TimingLogger] (l.Server-7) start[1402578142557] time[6] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2014-06-12 14:02:22,563 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Rslt:	()
2014-06-12 14:02:22,565 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Meth:	interface ome.api.IQuery.findAllByQuery
2014-06-12 14:02:22,565 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	[select obj from Screen obj join fetch obj.details.owner as owner join fetch obj.details.group join fetch obj.details.creationEvent where owner.id = (:eid), PARAMS:F(u2):eid=2 ]
2014-06-12 14:02:22,567 INFO  [    ome.security.basic.BasicEventContext] (l.Server-2)  cctx:	group=3
2014-06-12 14:02:22,567 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,570 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1402578142565] time[5] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2014-06-12 14:02:22,570 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	()
2014-06-12 14:02:22,572 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Meth:	interface ome.api.IQuery.findAllByQuery
2014-06-12 14:02:22,572 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Args:	[select obj from Plate as obj join fetch obj.details.creationEvent join fetch obj.details.owner join fetch obj.details.group where obj.details.owner.id=:eid and not exists (select obl from ScreenPlateLink as obl where obl.child=obj.id), PARAMS:eid=2 ]
2014-06-12 14:02:22,574 INFO  [    ome.security.basic.BasicEventContext] (l.Server-0)  cctx:	group=3
2014-06-12 14:02:22,574 INFO  [         ome.security.basic.EventHandler] (l.Server-0)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,578 INFO  [                 org.perf4j.TimingLogger] (l.Server-0) start[1402578142572] time[6] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2014-06-12 14:02:22,578 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Rslt:	()
2014-06-12 14:02:22,580 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface ome.api.IQuery.projection
2014-06-12 14:02:22,580 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	[select count(obj.id) from Image as obj join obj.details.creationEvent join obj.details.owner join obj.details.group where obj.details.owner.id=:eid and not exists (select obl from DatasetImageLink as obl where obl.child=obj.id)and not exists ( select ws from WellSample as ws where ws.image=obj.id  and ws.details.owner.id=:eid), PARAMS:eid=2 ]
2014-06-12 14:02:22,582 INFO  [    ome.security.basic.BasicEventContext] (l.Server-5)  cctx:	group=3
2014-06-12 14:02:22,582 INFO  [         ome.security.basic.EventHandler] (l.Server-5)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,587 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1402578142580] time[7] tag[omero.call.success.ome.logic.QueryImpl.projection]
2014-06-12 14:02:22,587 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	([0])
2014-06-12 14:02:22,602 INFO  [                      omero.cmd.SessionI] (l.Server-1) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IContainer(omero.api._IContainerTie@ba91a137)
2014-06-12 14:02:22,606 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,606 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:	[ome.model.containers.Project, datasetLinks, (301), null]
2014-06-12 14:02:22,608 INFO  [    ome.security.basic.BasicEventContext] (l.Server-1)  cctx:	group=3
2014-06-12 14:02:22,608 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,611 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1402578142606] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,611 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:	{301=6}
2014-06-12 14:02:22,613 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,614 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:	[ome.model.containers.Project, datasetLinks, (301), null]
2014-06-12 14:02:22,616 INFO  [    ome.security.basic.BasicEventContext] (l.Server-9)  cctx:	group=3
2014-06-12 14:02:22,616 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,619 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1402578142614] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,619 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:	{301=6}
2014-06-12 14:02:22,621 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,621 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Args:	[ome.model.containers.Project, datasetLinks, (301), null]
2014-06-12 14:02:22,624 INFO  [    ome.security.basic.BasicEventContext] (l.Server-8)  cctx:	group=3
2014-06-12 14:02:22,625 INFO  [         ome.security.basic.EventHandler] (l.Server-8)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,628 INFO  [                 org.perf4j.TimingLogger] (l.Server-8) start[1402578142621] time[7] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,628 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Rslt:	{301=6}
2014-06-12 14:02:22,632 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Meth:	interface ome.api.IQuery.findAllByQuery
2014-06-12 14:02:22,632 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Args:	[select c from ProjectDatasetLink as c join fetch c.child as ch
                     left outer join fetch ch.annotationLinks as ial
                     left outer join fetch ial.child as a  where c.parent.id=:dsid order by c.child.name, PARAMS:dsid=301 ]
2014-06-12 14:02:22,634 INFO  [    ome.security.basic.BasicEventContext] (l.Server-4)  cctx:	group=3
2014-06-12 14:02:22,634 INFO  [         ome.security.basic.EventHandler] (l.Server-4)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,756 INFO  [                 org.perf4j.TimingLogger] (l.Server-4) start[1402578142632] time[124] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2014-06-12 14:02:22,756 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Rslt:	(ome.model.containers.ProjectDatasetLink:Id_713, ome.model.containers.ProjectDatasetLink:Id_714, ome.model.containers.ProjectDatasetLink:Id_715, ... 3 more)
2014-06-12 14:02:22,815 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,815 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[ome.model.containers.Dataset, imageLinks, (963), null]
2014-06-12 14:02:22,820 INFO  [    ome.security.basic.BasicEventContext] (l.Server-3)  cctx:	group=3
2014-06-12 14:02:22,820 INFO  [         ome.security.basic.EventHandler] (l.Server-3)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,823 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1402578142815] time[8] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,823 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	{963=25}
2014-06-12 14:02:22,825 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,826 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Args:	[ome.model.containers.Dataset, imageLinks, (963), null]
2014-06-12 14:02:22,828 INFO  [    ome.security.basic.BasicEventContext] (l.Server-7)  cctx:	group=3
2014-06-12 14:02:22,828 INFO  [         ome.security.basic.EventHandler] (l.Server-7)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,830 INFO  [                 org.perf4j.TimingLogger] (l.Server-7) start[1402578142826] time[4] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,831 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Rslt:	{963=25}
2014-06-12 14:02:22,833 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,833 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	[ome.model.containers.Dataset, imageLinks, (963), null]
2014-06-12 14:02:22,835 INFO  [    ome.security.basic.BasicEventContext] (l.Server-2)  cctx:	group=3
2014-06-12 14:02:22,835 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,839 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1402578142833] time[6] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,839 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	{963=25}
2014-06-12 14:02:22,842 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,843 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Args:	[ome.model.containers.Dataset, imageLinks, (964), null]
2014-06-12 14:02:22,845 INFO  [    ome.security.basic.BasicEventContext] (l.Server-0)  cctx:	group=3
2014-06-12 14:02:22,845 INFO  [         ome.security.basic.EventHandler] (l.Server-0)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,848 INFO  [                 org.perf4j.TimingLogger] (l.Server-0) start[1402578142843] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,848 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Rslt:	{964=22}
2014-06-12 14:02:22,850 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,850 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	[ome.model.containers.Dataset, imageLinks, (964), null]
2014-06-12 14:02:22,852 INFO  [    ome.security.basic.BasicEventContext] (l.Server-5)  cctx:	group=3
2014-06-12 14:02:22,852 INFO  [         ome.security.basic.EventHandler] (l.Server-5)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,855 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1402578142850] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,855 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	{964=22}
2014-06-12 14:02:22,857 INFO  [        ome.services.util.ServiceHandler] (l.Server-6)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,858 INFO  [        ome.services.util.ServiceHandler] (l.Server-6)  Args:	[ome.model.containers.Dataset, imageLinks, (964), null]
2014-06-12 14:02:22,860 INFO  [    ome.security.basic.BasicEventContext] (l.Server-6)  cctx:	group=3
2014-06-12 14:02:22,860 INFO  [         ome.security.basic.EventHandler] (l.Server-6)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,863 INFO  [                 org.perf4j.TimingLogger] (l.Server-6) start[1402578142858] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,863 INFO  [        ome.services.util.ServiceHandler] (l.Server-6)  Rslt:	{964=22}
2014-06-12 14:02:22,865 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,866 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:	[ome.model.containers.Dataset, imageLinks, (965), null]
2014-06-12 14:02:22,867 INFO  [    ome.security.basic.BasicEventContext] (l.Server-1)  cctx:	group=3
2014-06-12 14:02:22,868 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,886 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1402578142866] time[20] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,886 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:	{965=22}
2014-06-12 14:02:22,889 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,889 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:	[ome.model.containers.Dataset, imageLinks, (965), null]
2014-06-12 14:02:22,891 INFO  [    ome.security.basic.BasicEventContext] (l.Server-9)  cctx:	group=3
2014-06-12 14:02:22,891 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,894 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1402578142889] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,894 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:	{965=22}
2014-06-12 14:02:22,896 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,896 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Args:	[ome.model.containers.Dataset, imageLinks, (965), null]
2014-06-12 14:02:22,898 INFO  [    ome.security.basic.BasicEventContext] (l.Server-8)  cctx:	group=3
2014-06-12 14:02:22,899 INFO  [         ome.security.basic.EventHandler] (l.Server-8)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,901 INFO  [                 org.perf4j.TimingLogger] (l.Server-8) start[1402578142897] time[4] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,901 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Rslt:	{965=22}
2014-06-12 14:02:22,904 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,904 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Args:	[ome.model.containers.Dataset, imageLinks, (966), null]
2014-06-12 14:02:22,906 INFO  [    ome.security.basic.BasicEventContext] (l.Server-4)  cctx:	group=3
2014-06-12 14:02:22,906 INFO  [         ome.security.basic.EventHandler] (l.Server-4)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,909 INFO  [                 org.perf4j.TimingLogger] (l.Server-4) start[1402578142904] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,909 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Rslt:	{966=22}
2014-06-12 14:02:22,911 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,913 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[ome.model.containers.Dataset, imageLinks, (966), null]
2014-06-12 14:02:22,916 INFO  [    ome.security.basic.BasicEventContext] (l.Server-3)  cctx:	group=3
2014-06-12 14:02:22,916 INFO  [         ome.security.basic.EventHandler] (l.Server-3)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,919 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1402578142913] time[6] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,919 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	{966=22}
2014-06-12 14:02:22,921 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,921 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Args:	[ome.model.containers.Dataset, imageLinks, (966), null]
2014-06-12 14:02:22,923 INFO  [    ome.security.basic.BasicEventContext] (l.Server-7)  cctx:	group=3
2014-06-12 14:02:22,923 INFO  [         ome.security.basic.EventHandler] (l.Server-7)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,932 INFO  [                 org.perf4j.TimingLogger] (l.Server-7) start[1402578142921] time[11] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,932 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Rslt:	{966=22}
2014-06-12 14:02:22,935 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,935 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	[ome.model.containers.Dataset, imageLinks, (967), null]
2014-06-12 14:02:22,938 INFO  [    ome.security.basic.BasicEventContext] (l.Server-2)  cctx:	group=3
2014-06-12 14:02:22,938 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,941 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1402578142935] time[6] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,941 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	{967=23}
2014-06-12 14:02:22,943 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,943 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Args:	[ome.model.containers.Dataset, imageLinks, (967), null]
2014-06-12 14:02:22,945 INFO  [    ome.security.basic.BasicEventContext] (l.Server-0)  cctx:	group=3
2014-06-12 14:02:22,946 INFO  [         ome.security.basic.EventHandler] (l.Server-0)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,951 INFO  [                 org.perf4j.TimingLogger] (l.Server-0) start[1402578142943] time[8] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,951 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Rslt:	{967=23}
2014-06-12 14:02:22,953 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,954 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	[ome.model.containers.Dataset, imageLinks, (967), null]
2014-06-12 14:02:22,956 INFO  [    ome.security.basic.BasicEventContext] (l.Server-5)  cctx:	group=3
2014-06-12 14:02:22,956 INFO  [         ome.security.basic.EventHandler] (l.Server-5)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,959 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1402578142954] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,959 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	{967=23}
2014-06-12 14:02:22,962 INFO  [        ome.services.util.ServiceHandler] (l.Server-6)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,962 INFO  [        ome.services.util.ServiceHandler] (l.Server-6)  Args:	[ome.model.containers.Dataset, imageLinks, (968), null]
2014-06-12 14:02:22,964 INFO  [    ome.security.basic.BasicEventContext] (l.Server-6)  cctx:	group=3
2014-06-12 14:02:22,964 INFO  [         ome.security.basic.EventHandler] (l.Server-6)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,967 INFO  [                 org.perf4j.TimingLogger] (l.Server-6) start[1402578142962] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,967 INFO  [        ome.services.util.ServiceHandler] (l.Server-6)  Rslt:	{968=22}
2014-06-12 14:02:22,969 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,969 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:	[ome.model.containers.Dataset, imageLinks, (968), null]
2014-06-12 14:02:22,971 INFO  [    ome.security.basic.BasicEventContext] (l.Server-1)  cctx:	group=3
2014-06-12 14:02:22,971 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,974 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1402578142969] time[5] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,974 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:	{968=22}
2014-06-12 14:02:22,976 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Meth:	interface ome.api.IContainer.getCollectionCount
2014-06-12 14:02:22,977 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:	[ome.model.containers.Dataset, imageLinks, (968), null]
2014-06-12 14:02:22,985 INFO  [    ome.security.basic.BasicEventContext] (l.Server-9)  cctx:	group=3
2014-06-12 14:02:22,985 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:22,988 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1402578142977] time[11] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount]
2014-06-12 14:02:22,988 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:	{968=22}
2014-06-12 14:02:22,991 INFO  [e.services.sessions.SessionContext$Count] (l.Server-8) -Reference count: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d=1
2014-06-12 14:02:22,991 INFO  [                      omero.cmd.SessionI] (l.Server-8) cleanupSelf(ServiceFactoryI(session-7be4eb97-09d9-4d4e-a32a-5f09e8d93c37/5a1dc603-b2de-4dfc-99a6-6fe724e2521d)).
2014-06-12 14:02:22,991 INFO  [                      omero.cmd.SessionI] (l.Server-8) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IQuery(omero.api._IQueryTie@a4206d46)
2014-06-12 14:02:22,991 INFO  [                      omero.cmd.SessionI] (l.Server-8) Removed servant from adapter: 7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IQuery
2014-06-12 14:02:22,991 INFO  [                      omero.cmd.SessionI] (l.Server-8) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IAdmin(omero.api._IAdminTie@7bc78b07)
2014-06-12 14:02:22,991 INFO  [                      omero.cmd.SessionI] (l.Server-8) Removed servant from adapter: 7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IAdmin
2014-06-12 14:02:22,992 INFO  [                      omero.cmd.SessionI] (l.Server-8) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IContainer(omero.api._IContainerTie@ba91a137)
2014-06-12 14:02:22,992 INFO  [                      omero.cmd.SessionI] (l.Server-8) Removed servant from adapter: 7be4eb97-09d9-4d4e-a32a-5f09e8d93c37omero.api.IContainer
2014-06-12 14:02:23,003 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-8) Found session locally: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:23,004 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-8) Rejoining session ServiceFactoryI(session-c5f795fe-7be9-4ef4-b87d-406cc4a71fd8/5a1dc603-b2de-4dfc-99a6-6fe724e2521d) (agent=OMERO.web)
2014-06-12 14:02:23,012 INFO  [                      omero.cmd.SessionI] (l.Server-7) Added servant to adapter: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d/c5f795fe-7be9-4ef4-b87d-406cc4a71fd8omero.api.IAdmin(omero.api._IAdminTie@8f6a4ada)
2014-06-12 14:02:23,013 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface ome.api.IAdmin.getEventContext
2014-06-12 14:02:23,013 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	()
2014-06-12 14:02:23,016 INFO  [         ome.security.basic.EventHandler] (l.Server-5)  Auth:	user=2,group=3,event=null(User),sess=5a1dc603-b2de-4dfc-99a6-6fe724e2521d
2014-06-12 14:02:23,016 INFO  [        ome.services.util.ServiceHandler] (2-thread-4)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(5a1dc603-b2de-4dfc-99a6-6fe724e2521d)
2014-06-12 14:02:23,016 INFO  [        ome.services.util.ServiceHandler] (2-thread-4)  Args:	[null, InternalSF@6008138]
2014-06-12 14:02:23,018 INFO  [         ome.security.basic.EventHandler] (2-thread-4)  Auth:	user=0,group=0,event=null(Sessions),sess=05f58b4e-1ead-4ed5-b5a8-3238c7064e62
2014-06-12 14:02:23,025 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1402578143016] time[9] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2014-06-12 14:02:23,025 INFO  [        ome.services.util.ServiceHandler] (2-thread-4)  Rslt:	(ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, (3, 1), ... 4 more)
2014-06-12 14:02:23,026 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1402578143013] time[13] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2014-06-12 14:02:23,026 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	ome.system.SimpleEventContext@791de364
2014-06-12 14:02:23,042 INFO  [e.services.sessions.SessionContext$Count] (l.Server-6) -Reference count: 5a1dc603-b2de-4dfc-99a6-6fe724e2521d=1
2014-06-12 14:02:23,042 INFO  [                      omero.cmd.SessionI] (l.Server-6) cleanupSelf(ServiceFactoryI(session-c5f795fe-7be9-4ef4-b87d-406cc4a71fd8/5a1dc603-b2de-4dfc-99a6-6fe724e2521d)).
2014-06-12 14:02:23,042 INFO  [                      omero.cmd.SessionI] (l.Server-6) Unregistered servant:5a1dc603-b2de-4dfc-99a6-6fe724e2521d/c5f795fe-7be9-4ef4-b87d-406cc4a71fd8omero.api.IAdmin(omero.api._IAdminTie@8f6a4ada)
2014-06-12 14:02:23,042 INFO  [                      omero.cmd.SessionI] (l.Server-6) Removed servant from adapter: c5f795fe-7be9-4ef4-b87d-406cc4a71fd8omero.api.IAdmin