2016-09-07 12:25:56,007 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955976] time[21] tag[omero.call.success.ome.logic.PojosImpl.loadContainerHierarchy]
2016-09-07 12:25:37,396 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO[guest]
2016-09-07 12:25:37,396 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:37,396 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:37,411 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243937396] time[5] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2016-09-07 12:25:37,411 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	true
2016-09-07 12:25:37,411 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession[]
2016-09-07 12:25:37,411 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:37,411 INFO  [    ome.security.basic.BasicEventContext] (rver-21929)  cctx:	group=0
2016-09-07 12:25:37,427 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=0,group=0,event=209525(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:37,443 INFO  [       ome.security.basic.CurrentDetails] (rver-21929) Adding log:INSERT,class ome.model.meta.Session,40574
2016-09-07 12:25:37,458 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243937411] time[45] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
2016-09-07 12:25:37,458 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	(ome.model.meta.Experimenter:Id_1, ome.model.meta.ExperimenterGroup:Id_2, (2), ... 4 more)
2016-09-07 12:25:37,458 INFO  [ ome.services.blitz.fire.SessionManagerI] (rver-21929) Created session ServiceFactoryI(session-8b6e532f-3a4e-405b-b9b9-68afd719a4c4/47091f47-f1ae-4f07-9762-2e721c9a68ec) for user guest (agent=OMERO.importer)
2016-09-07 12:25:37,505 INFO  [                      omero.cmd.SessionI] (rver-21926) Added servant to adapter: 47091f47-f1ae-4f07-9762-2e721c9a68ec/8b6e532f-3a4e-405b-b9b9-68afd719a4c4omero.api.ISession(omero.api._ISessionTie@b6559202)
2016-09-07 12:25:37,521 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Meth:	interface ome.api.ISession.getSession
2016-09-07 12:25:37,521 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	[Kunis]
2016-09-07 12:25:37,521 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243937521] time[0] tag[omero.call.exception]
2016-09-07 12:25:37,521 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Excp:	ome.conditions.RemovedSessionException: No context for Kunis
2016-09-07 12:25:37,568 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO[Kunis]
2016-09-07 12:25:37,568 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:37,568 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:37,568 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243937568] time[1] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2016-09-07 12:25:37,568 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	true
2016-09-07 12:25:37,568 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession[]
2016-09-07 12:25:37,568 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:37,568 INFO  [    ome.security.basic.BasicEventContext] (rver-21931)  cctx:	group=0
2016-09-07 12:25:37,568 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=0,group=0,event=209526(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:37,568 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.meta.Session,40575
2016-09-07 12:25:37,583 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243937568] time[14] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
2016-09-07 12:25:37,583 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:37,583 INFO  [ ome.services.blitz.fire.SessionManagerI] (rver-21931) Created session ServiceFactoryI(session-828bde45-d6d5-4eef-b536-b8df046cecf0/a3ca7472-f3d2-4250-bb0b-859e614e690f) for user Kunis (agent=OMERO.importer)
2016-09-07 12:25:37,583 INFO  [                      omero.cmd.SessionI] (rver-21928) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/828bde45-d6d5-4eef-b536-b8df046cecf0omero.api.IConfig(omero.api._IConfigTie@70ca07af)
2016-09-07 12:25:37,583 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.IConfig.getVersion
2016-09-07 12:25:37,583 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	()
2016-09-07 12:25:37,599 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=209527(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:37,599 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243937583] time[6] tag[omero.call.success.ome.logic.ConfigImpl.getVersion]
2016-09-07 12:25:37,599 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	5.2.4
2016-09-07 12:25:37,599 INFO  [                      omero.cmd.SessionI] (rver-21925) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/828bde45-d6d5-4eef-b536-b8df046cecf0omero.api.IAdmin(omero.api._IAdminTie@b8444f65)
2016-09-07 12:25:37,630 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IAdmin.getEventContext
2016-09-07 12:25:37,630 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	()
2016-09-07 12:25:37,630 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:37,630 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[a3ca7472-f3d2-4250-bb0b-859e614e690f]
2016-09-07 12:25:37,630 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:37,646 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:37,662 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243937630] time[31] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:37,662 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:37,662 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243937630] time[32] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2016-09-07 12:25:37,662 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	ome.system.SimpleEventContext@4196316b
2016-09-07 12:25:37,662 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.IAdmin.lookupExperimenter
2016-09-07 12:25:37,662 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[Kunis]
2016-09-07 12:25:37,662 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:37,677 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243937662] time[2] tag[omero.call.success.ome.logic.AdminImpl.lookupExperimenter]
2016-09-07 12:25:37,677 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	ome.model.meta.Experimenter:Id_52
2016-09-07 12:25:37,708 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Meth:	interface ome.api.IConfig.getConfigValue
2016-09-07 12:25:37,708 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	[omero.router.insecure]
2016-09-07 12:25:37,708 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=209528(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:37,708 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243937708] time[7] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
2016-09-07 12:25:37,708 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	OMERO.Glacier2/router:tcp -p 4063 -h @omero.host@
2016-09-07 12:25:37,724 INFO  [ ome.services.blitz.fire.SessionManagerI] (rver-21925) Found session locally: a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:37,724 INFO  [ ome.services.blitz.fire.SessionManagerI] (rver-21925) Rejoining session ServiceFactoryI(session-cf1281d1-cd08-48f5-9b93-340c2f8cbb45/a3ca7472-f3d2-4250-bb0b-859e614e690f) (agent=OMERO.importer;secure=false)
2016-09-07 12:25:38,068 INFO  [                      omero.cmd.SessionI] (rver-21931) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.IConfig(omero.api._IConfigTie@619c5d77)
2016-09-07 12:25:38,068 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Meth:	interface ome.api.IConfig.getClientConfigValues
2016-09-07 12:25:38,068 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	()
2016-09-07 12:25:38,068 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=209529(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:38,083 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243938068] time[14] tag[omero.call.success.ome.logic.ConfigImpl.getClientConfigValues]
2016-09-07 12:25:38,083 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	{omero.client.ui.tree.orphans.name=Orphaned Images, omero.client.ui.menu.dropdown.leaders=Owners, omero.client.viewer.initial_zoom_level=0, ... 15 more}
2016-09-07 12:25:38,083 INFO  [                      omero.cmd.SessionI] (rver-21928) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.IQuery(omero.api._IQueryTie@788e6668)
2016-09-07 12:25:38,115 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.IQuery.findAllByQuery
2016-09-07 12:25:38,115 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[select distinct g from ExperimenterGroup as g join fetch g.groupExperimenterMap as map join fetch ma, PARAMS:id=52 ]
2016-09-07 12:25:38,115 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:38,130 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243938115] time[8] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2016-09-07 12:25:38,130 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	(ome.model.meta.ExperimenterGroup:Id_0, ome.model.meta.ExperimenterGroup:Id_1, ome.model.meta.ExperimenterGroup:Id_3, ... 2 more)
2016-09-07 12:25:38,146 INFO  [                      omero.cmd.SessionI] (rver-21925) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.IAdmin(omero.api._IAdminTie@b608f706)
2016-09-07 12:25:38,146 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IAdmin.getSecurityRoles
2016-09-07 12:25:38,146 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	()
2016-09-07 12:25:38,146 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:38,146 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243938146] time[0] tag[omero.call.success.ome.logic.AdminImpl.getSecurityRoles]
2016-09-07 12:25:38,146 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	ome.system.Roles@70b2c5d0
2016-09-07 12:25:38,896 INFO  [                      omero.cmd.SessionI] (rver-21931) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.IRepositoryInfo(omero.api._IRepositoryInfoTie@a3bd73c2)
2016-09-07 12:25:38,896 INFO  [                      omero.cmd.SessionI] (rver-21929) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.IContainer(omero.api._IContainerTie@be36fe9c)
2016-09-07 12:25:38,896 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Meth:	interface ome.api.IRepositoryInfo.getFreeSpaceInKilobytes
2016-09-07 12:25:38,896 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	()
2016-09-07 12:25:38,912 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=209530(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:38,912 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.IContainer.loadContainerHierarchy
2016-09-07 12:25:38,912 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[class ome.model.containers.Project, (), PARAMS:O[O]]
2016-09-07 12:25:38,912 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:38,927 INFO  [            ome.logic.RepositoryInfoImpl] (rver-21928) Total kilobytes free: 17411776024
2016-09-07 12:25:38,927 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243938896] time[19] tag[omero.call.success.ome.logic.RepositoryInfoImpl.getFreeSpaceInKilobytes]
2016-09-07 12:25:38,927 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	17411776024
2016-09-07 12:25:38,943 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243938912] time[26] tag[omero.call.success.ome.logic.PojosImpl.loadContainerHierarchy]
2016-09-07 12:25:38,943 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	(ome.model.containers.Project:Id_202, ome.model.containers.Dataset:Id_53, ome.model.containers.Project:Id_851, ... 14 more)
2016-09-07 12:25:54,507 INFO  [                      omero.cmd.SessionI] (rver-21930) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.IUpdate(omero.api._IUpdateTie@862d6db8)
2016-09-07 12:25:54,507 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.IUpdate.saveAndReturnObject
2016-09-07 12:25:54,507 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[ome.model.containers.Dataset:Hash_355374154]
2016-09-07 12:25:54,507 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209531(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,507 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.containers.Dataset,1480
2016-09-07 12:25:54,507 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954507] time[8] tag[omero.call.success.ome.logic.UpdateImpl.saveAndReturnObject]
2016-09-07 12:25:54,507 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	ome.model.containers.Dataset:Id_1480
2016-09-07 12:25:54,523 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Meth:	interface ome.api.IQuery.find
2016-09-07 12:25:54,523 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[class ome.model.containers.Project, 601]
2016-09-07 12:25:54,523 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,523 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243954523] time[2] tag[omero.call.success.ome.logic.QueryImpl.find]
2016-09-07 12:25:54,523 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	ome.model.containers.Project:Id_601
2016-09-07 12:25:54,538 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Meth:	interface ome.api.IUpdate.saveAndReturnObject
2016-09-07 12:25:54,538 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	[ome.model.containers.ProjectDatasetLink:Hash_2124438540]
2016-09-07 12:25:54,538 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=209532(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,538 INFO  [       ome.security.basic.CurrentDetails] (rver-21928) Adding log:INSERT,class ome.model.containers.ProjectDatasetLink,1480
2016-09-07 12:25:54,538 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243954538] time[9] tag[omero.call.success.ome.logic.UpdateImpl.saveAndReturnObject]
2016-09-07 12:25:54,538 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	ome.model.containers.ProjectDatasetLink:Id_1480
2016-09-07 12:25:54,538 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.IQuery.find
2016-09-07 12:25:54,538 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[class ome.model.containers.Dataset, 1480]
2016-09-07 12:25:54,538 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,538 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243954538] time[1] tag[omero.call.success.ome.logic.QueryImpl.find]
2016-09-07 12:25:54,538 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	ome.model.containers.Dataset:Id_1480
2016-09-07 12:25:54,570 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.IAdmin.getEventContext
2016-09-07 12:25:54,570 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	()
2016-09-07 12:25:54,570 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,570 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[a3ca7472-f3d2-4250-bb0b-859e614e690f]
2016-09-07 12:25:54,570 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,570 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:54,570 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243954570] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:54,570 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:54,570 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954570] time[5] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2016-09-07 12:25:54,570 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	ome.system.SimpleEventContext@3fe4ea89
2016-09-07 12:25:54,570 INFO  [                      omero.cmd.SessionI] (rver-21928) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/357897f9-279f-4edf-8765-ae922186926aomero.api.RawFileStore(omero.api._RawFileStoreTie@d47acd43)
2016-09-07 12:25:54,585 INFO  [                      omero.cmd.SessionI] (rver-21926) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/ccd0383a-5fac-4e5c-8e24-9b8c6b5ac24fomero.api.RawPixelsStore(omero.api._RawPixelsStoreTie@a631baf5)
2016-09-07 12:25:54,601 INFO  [                      omero.cmd.SessionI] (rver-21925) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/47850c80-f169-4bcb-8b4e-240af06fa733omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@ce99bc5e)
2016-09-07 12:25:54,601 INFO  [                      omero.cmd.SessionI] (rver-21929) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.IRenderingSettings(omero.api._IRenderingSettingsTie@621afa96)
2016-09-07 12:25:54,616 INFO  [                      omero.cmd.SessionI] (rver-21928) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.MetadataStore(omero.api._MetadataStoreTie@a3d46356)
2016-09-07 12:25:54,663 INFO  [                      omero.cmd.SessionI] (rver-21925) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.grid.SharedResources(omero.grid._SharedResourcesTie@7bb0853c)
2016-09-07 12:25:54,663 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Executor.doWork -- ome.services.blitz.impl.SharedResourcesI.acquireRepositories[]
2016-09-07 12:25:54,663 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,663 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,679 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243954663] time[8] tag[omero.call.success.ome.services.blitz.impl.SharedResourcesI$1.doWork]
2016-09-07 12:25:54,679 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	(ome.model.core.OriginalFile:Id_1, ome.model.core.OriginalFile:Id_2, ome.model.core.OriginalFile:Id_3)
2016-09-07 12:25:54,679 INFO  [        ome.services.blitz.fire.Registry] (rver-21930) Found 3 repo(s) : [InternalRepository-ScriptRepo -t -e 1.1:tcp -h 192.168.1.151 -p 53954, InternalRepository-71c8ddc8-90f3-4156-88c2-7b407cb5b3a6 -t -e 1.1:tcp -h 192.168.1.151 -p 53954, InternalRepository-f2e7bd19-19d0-46e6-8fff-09995ed02df5 -t -e 1.1:tcp -h 192.168.1.151 -p 53954]
2016-09-07 12:25:54,726 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.getEventContext[]
2016-09-07 12:25:54,726 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,726 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954726] time[0] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$20.doWork]
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	ome.system.SimpleEventContext@925ac14
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.makeDirs[(CheckedPath(Kunis_52))]
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,741 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954741] time[0] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$13.doWork]
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	ome.system.SimpleEventContext@66497ac2
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.makeDirs[(CheckedPath(Kunis_52))]
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,741 INFO  [    ome.security.basic.BasicEventContext] (rver-21931)  cctx:	group=1
2016-09-07 12:25:54,741 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=0,group=1,event=209533(Internal),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[236d3c93-89c0-47de-93fe-83d4dfb21f17]
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,741 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243954741] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_0, ome.model.meta.ExperimenterGroup:Id_0, (0, 1, 103), ... 4 more)
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954741] time[0] tag[omero.repo.internal_register.find_repo_files]
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954741] time[0] tag[omero.repo.internal_register.load]
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954741] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954741] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954741] time[0] tag[omero.repo.user_dir]
2016-09-07 12:25:54,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954741] time[13] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$14.doWork]
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.makeDirs[(CheckedPath(Kunis_52/2016-09))]
2016-09-07 12:25:54,741 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,757 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209534(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,757 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[a3ca7472-f3d2-4250-bb0b-859e614e690f]
2016-09-07 12:25:54,757 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,757 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243954757] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:54,757 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954757] time[0] tag[omero.repo.internal_register.find_repo_files]
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954757] time[0] tag[omero.repo.internal_register.load]
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954757] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954757] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954757] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954757] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954757] time[0] tag[omero.repo.user_dir]
2016-09-07 12:25:54,757 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954741] time[14] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$14.doWork]
2016-09-07 12:25:54,757 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:54,757 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.makeDirs[(CheckedPath(Kunis_52/2016-09/07))]
2016-09-07 12:25:54,757 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,773 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209535(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,773 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[a3ca7472-f3d2-4250-bb0b-859e614e690f]
2016-09-07 12:25:54,773 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,773 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243954773] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:54,773 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.internal_register.find_repo_files]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.internal_register.load]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[0] tag[omero.repo.user_dir]
2016-09-07 12:25:54,773 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954757] time[14] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$14.doWork]
2016-09-07 12:25:54,773 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:54,773 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.makeDirs[(CheckedPath(Kunis_52/2016-09/07/12-25-54.741))]
2016-09-07 12:25:54,773 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,788 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209536(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,788 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[a3ca7472-f3d2-4250-bb0b-859e614e690f]
2016-09-07 12:25:54,788 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,788 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243954788] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:54,788 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[0] tag[omero.repo.internal_register.find_repo_files]
2016-09-07 12:25:54,788 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.core.OriginalFile,15751
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[1] tag[omero.repo.create_original_file.save]
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[0] tag[omero.repo.create_original_file.internal_mkdir]
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[0] tag[omero.repo.create_original_file.set_file_repo]
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[0] tag[omero.repo.internal_register.load]
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,788 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:UPDATE,class ome.model.core.OriginalFile,15751
2016-09-07 12:25:54,788 INFO  [                     ome.logic.AdminImpl] (rver-21931) Moved object to common space: ome.model.core.OriginalFile:Id_15751
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[1] tag[omero.repo.file.move_to_common]
2016-09-07 12:25:54,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954788] time[1] tag[omero.repo.user_dir]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954773] time[16] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$14.doWork]
2016-09-07 12:25:54,804 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:54,804 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.makeDirs[(CheckedPath(Kunis_52/2016-09/07/12-25-54.741), CheckedPath(Kunis_52/2016-09/07/12-25-54.741))]
2016-09-07 12:25:54,804 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,804 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209537(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,804 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[a3ca7472-f3d2-4250-bb0b-859e614e690f]
2016-09-07 12:25:54,804 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,804 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243954804] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:54,804 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.internal_register.find_repo_files]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.internal_register.load]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[0] tag[omero.repo.user_dir]
2016-09-07 12:25:54,804 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[a3ca7472-f3d2-4250-bb0b-859e614e690f]
2016-09-07 12:25:54,804 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,804 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243954804] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:54,820 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.internal_register.find_repo_files]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.internal_register.load]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.file.find]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.file.check_group]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[0] tag[omero.repo.user_dir]
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954804] time[21] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$14.doWork]
2016-09-07 12:25:54,820 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:54,820 INFO  [       ome.formats.importer.ImportConfig] (rver-21931) OMERO Version: 5.2.4-ice35-b23
2016-09-07 12:25:54,820 INFO  [       ome.formats.importer.ImportConfig] (rver-21931) Bioformats version: 5.1.9 revision: c3065feb775a7a8c0cc2cf6e35979331cca2418b date: 14 April 2016
2016-09-07 12:25:54,820 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.ManagedRepositoryI.persistLogFile[2]
2016-09-07 12:25:54,820 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,820 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209538(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,820 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.core.OriginalFile,15752
2016-09-07 12:25:54,820 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[7] tag[omero.call.success.ome.services.blitz.repo.PublicRepositoryI$2.doWork]
2016-09-07 12:25:54,820 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	ome.model.core.OriginalFile:Id_15752
2016-09-07 12:25:54,820 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.saveFileset[f2e7bd19-19d0-46e6-8fff-09995ed02df5, ome.model.fs.Fileset:Hash_2101633635, (CheckedPath(Kunis_52/2016-09/07/12-25-54.741/CK-067-01-SL1344-25-SiiE-001.tif))]
2016-09-07 12:25:54,820 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,835 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209539(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,835 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.jobs.UploadJob,21427
2016-09-07 12:25:54,851 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.jobs.JobOriginalFileLink,19975
2016-09-07 12:25:54,866 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.jobs.MetadataImportJob,21428
2016-09-07 12:25:54,866 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.jobs.PixelDataJob,21429
2016-09-07 12:25:54,882 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.jobs.ThumbnailGenerationJob,21430
2016-09-07 12:25:54,882 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.jobs.IndexingJob,21431
2016-09-07 12:25:54,898 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954898] time[0] tag[omero.repo.internal_register.find_repo_files]
2016-09-07 12:25:54,898 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.core.OriginalFile,15753
2016-09-07 12:25:54,898 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954898] time[2] tag[omero.repo.create_original_file.save]
2016-09-07 12:25:54,898 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954898] time[0] tag[omero.repo.create_original_file.internal_mkdir]
2016-09-07 12:25:54,898 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954898] time[0] tag[omero.repo.create_original_file.set_file_repo]
2016-09-07 12:25:54,898 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954898] time[0] tag[omero.repo.internal_register.load]
2016-09-07 12:25:54,898 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954898] time[5] tag[omero.repo.save_fileset.register]
2016-09-07 12:25:54,898 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954898] time[0] tag[omero.repo.save_fileset.update_fileset_entries]
2016-09-07 12:25:54,898 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.fs.Fileset,2427
2016-09-07 12:25:54,898 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.fs.FilesetJobLink,9331
2016-09-07 12:25:54,913 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.fs.FilesetJobLink,9332
2016-09-07 12:25:54,913 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.fs.FilesetJobLink,9333
2016-09-07 12:25:54,913 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.fs.FilesetJobLink,9334
2016-09-07 12:25:54,913 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.fs.FilesetJobLink,9335
2016-09-07 12:25:54,913 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.fs.FilesetEntry,2430
2016-09-07 12:25:54,913 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954898] time[12] tag[omero.repo.save_fileset.save]
2016-09-07 12:25:54,929 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[99] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$8.doWork]
2016-09-07 12:25:54,929 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	ome.model.fs.Fileset:Id_2427
2016-09-07 12:25:54,929 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243954820] time[99] tag[omero.repo.save_fileset]
2016-09-07 12:25:54,929 INFO  [                      omero.cmd.SessionI] (rver-21931) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/ae38b6f8-8701-4c09-ad1a-fc1e3d121f55-ManagedImportProcessI(omero.grid._ImportProcessTie@94b79556)
2016-09-07 12:25:54,960 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.findRepoFile[f2e7bd19-19d0-46e6-8fff-09995ed02df5, CheckedPath(Kunis_52/2016-09/07/12-25-54.741/CK-067-01-SL1344-25-SiiE-001.tif), null]
2016-09-07 12:25:54,960 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,960 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,960 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243954960] time[6] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$2.doWork]
2016-09-07 12:25:54,960 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	ome.model.core.OriginalFile:Id_15753
2016-09-07 12:25:54,960 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.canUpdate[]
2016-09-07 12:25:54,960 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,960 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,976 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243954960] time[2] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$4.doWork]
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	true
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.getOriginalFile[15753]
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:54,976 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,976 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243954976] time[2] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$6.doWork]
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	ome.model.core.OriginalFile:Id_15753
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.setFileIdWithBuffer[15753, CheckedPath(Kunis_52/2016-09/07/12-25-54.741/CK-067-01-SL1344-25-SiiE-001.tif), rw]
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[null, InternalSF@1002063225]
2016-09-07 12:25:54,976 INFO  [    ome.security.basic.BasicEventContext] (rver-21929)  cctx:	group=3
2016-09-07 12:25:54,976 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,976 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243954976] time[2] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$1.doWork]
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	null
2016-09-07 12:25:54,976 INFO  [                      omero.cmd.SessionI] (rver-21929) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/a73c4f6a-b1e2-405c-9155-87824ff2c7f0-RepoRawFileStoreI(omero.api._RawFileStoreTie@9ec75bad)
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Meth:	interface ome.api.RawFileStore.write
2016-09-07 12:25:54,976 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	[[], 0, 0]
2016-09-07 12:25:54,976 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:54,991 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243954976] time[1] tag[omero.call.success.ome.services.RawFileBean.write]
2016-09-07 12:25:54,991 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	null
2016-09-07 12:25:55,007 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.RawFileStore.write
2016-09-07 12:25:55,007 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[[73, 73, 42, ... 1 more], 0, 818336]
2016-09-07 12:25:55,007 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,023 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243955007] time[1] tag[omero.call.success.ome.services.RawFileBean.write]
2016-09-07 12:25:55,023 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	null
2016-09-07 12:25:55,023 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Meth:	interface ome.api.RawFileStore.save
2016-09-07 12:25:55,023 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	()
2016-09-07 12:25:55,023 INFO  [         ome.security.basic.EventHandler] (rver-21925)  Auth:	user=52,group=3,event=209540(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,148 INFO  [       ome.security.basic.CurrentDetails] (rver-21925) Adding log:UPDATE,class ome.model.core.OriginalFile,15753
2016-09-07 12:25:55,148 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955023] time[122] tag[omero.call.success.ome.services.RawFileBean.save]
2016-09-07 12:25:55,148 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Rslt:	ome.model.core.OriginalFile:Id_15753
2016-09-07 12:25:55,148 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.StatefulServiceInterface.close
2016-09-07 12:25:55,148 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	()
2016-09-07 12:25:55,148 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209541(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,148 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955148] time[5] tag[omero.call.success.ome.services.RawFileBean.close]
2016-09-07 12:25:55,148 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:55,148 INFO  [        ome.services.util.ServiceHandler] (rver-21931) Cleanup: ome.services.RawFileBean@3dd9f9a2
2016-09-07 12:25:55,148 INFO  [                      omero.cmd.SessionI] (rver-21931) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/a73c4f6a-b1e2-405c-9155-87824ff2c7f0-RepoRawFileStoreI(omero.api._RawFileStoreTie@9ec75bad)
2016-09-07 12:25:55,163 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.updateJob[ome.model.jobs.UploadJob:Id_21427, Finished, Finished]
2016-09-07 12:25:55,163 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,179 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=209542(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,195 INFO  [       ome.security.basic.CurrentDetails] (rver-21930) Adding log:UPDATE,class ome.model.jobs.UploadJob,21427
2016-09-07 12:25:55,195 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955163] time[21] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$12.doWork]
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	null
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.findRepoFile[f2e7bd19-19d0-46e6-8fff-09995ed02df5, CheckedPath(Kunis_52/2016-09/07/12-25-54.741.log), null]
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,195 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,195 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955195] time[2] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$2.doWork]
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	ome.model.core.OriginalFile:Id_15752
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Executor.doWork -- ome.services.blitz.repo.RepositoryDaoImpl.canUpdate[]
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,195 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,195 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955195] time[1] tag[omero.call.success.ome.services.blitz.repo.RepositoryDaoImpl$4.doWork]
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	true
2016-09-07 12:25:55,195 INFO  [       ome.formats.importer.ImportConfig] (rver-21930) OMERO Version: 5.2.4-ice35-b23
2016-09-07 12:25:55,195 INFO  [       ome.formats.importer.ImportConfig] (rver-21930) Bioformats version: 5.1.9 revision: c3065feb775a7a8c0cc2cf6e35979331cca2418b date: 14 April 2016
2016-09-07 12:25:55,195 INFO  [                      omero.cmd.SessionI] (rver-21930) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/IHandle6ec18e8d-e24f-4f37-994c-664991053448(omero.cmd._HandleTie@83ca13a6)
2016-09-07 12:25:55,195 DEBUG [ o.s.b.r.ManagedImportRequestI.@6420412d] (2-thread-4) User callContext: {omero.client.uuid=cf1281d1-cd08-48f5-9b93-340c2f8cbb45, omero.session.uuid=a3ca7472-f3d2-4250-bb0b-859e614e690f}
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (2-thread-4)  Executor.doWork -- omero.cmd.HandleI.run[a3ca7472-f3d2-4250-bb0b-859e614e690f/IHandle6ec18e8d-e24f-4f37-994c-664991053448, ome.services.blitz.repo.ManagedImportRequestI@6420412d]
2016-09-07 12:25:55,195 INFO  [        ome.services.util.ServiceHandler] (2-thread-4)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,195 INFO  [         ome.security.basic.EventHandler] (2-thread-4)  Auth:	user=52,group=3,event=209543(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,195 INFO  [       ome.formats.importer.ImportConfig] (2-thread-4) OMERO Version: 5.2.4-ice35-b23
2016-09-07 12:25:55,195 INFO  [       ome.formats.importer.ImportConfig] (2-thread-4) Bioformats version: 5.1.9 revision: c3065feb775a7a8c0cc2cf6e35979331cca2418b date: 14 April 2016
2016-09-07 12:25:55,210 INFO  [ ome.services.blitz.fire.SessionManagerI] (rver-21926) Found session locally: a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,210 INFO  [ ome.services.blitz.fire.SessionManagerI] (rver-21926) Rejoining session ServiceFactoryI(session-71128637-d696-45dc-9f21-163c1feae518/a3ca7472-f3d2-4250-bb0b-859e614e690f) (agent=null)
2016-09-07 12:25:55,210 INFO  [    ome.formats.OMEROMetadataStoreClient] (2-thread-4) Call context: {omero.logfilename:Kunis_52/2016-09/07/12-25-54.741.log}
2016-09-07 12:25:55,210 INFO  [                      omero.cmd.SessionI] (rver-21931) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IAdmin(omero.api._IAdminTie@c6115469)
2016-09-07 12:25:55,210 INFO  [                      omero.cmd.SessionI] (rver-21929) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IQuery(omero.api._IQueryTie@d5f57b29)
2016-09-07 12:25:55,210 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IAdmin.getEventContext
2016-09-07 12:25:55,210 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	()
2016-09-07 12:25:55,210 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,210 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload[a3ca7472-f3d2-4250-bb0b-859e614e690f]
2016-09-07 12:25:55,210 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,210 INFO  [                      omero.cmd.SessionI] (rver-21928) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/ae38b6f8-8701-4c09-ad1a-fc1e3d121f55-ManagedImportProcessI(omero.grid._ImportProcessTie@94b79556)
2016-09-07 12:25:55,210 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (rver-21926) getRequest: ome.services.blitz.repo.ManagedImportRequestI@6420412d
2016-09-07 12:25:55,226 INFO  [         ome.security.basic.EventHandler] (thread-338)  Auth:	user=0,group=0,event=null(Sessions),sess=236d3c93-89c0-47de-93fe-83d4dfb21f17
2016-09-07 12:25:55,241 INFO  [                 org.perf4j.TimingLogger] (thread-338) start[1473243955210] time[32] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2016-09-07 12:25:55,241 INFO  [        ome.services.util.ServiceHandler] (thread-338)  Rslt:	(ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_3, (3, 0, 1, ... 2 more), ... 4 more)
2016-09-07 12:25:55,241 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955210] time[33] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2016-09-07 12:25:55,241 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	ome.system.SimpleEventContext@178babb6
2016-09-07 12:25:55,241 INFO  [                      omero.cmd.SessionI] (rver-21925) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IUpdate(omero.api._IUpdateTie@c60fc948)
2016-09-07 12:25:55,241 INFO  [                      omero.cmd.SessionI] (rver-21931) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/ec06403e-6a10-4f94-9aa4-14139e2409c7omero.api.RawFileStore(omero.api._RawFileStoreTie@dabcb8ad)
2016-09-07 12:25:55,241 INFO  [                      omero.cmd.SessionI] (rver-21929) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/1907d356-33bd-4bf3-86d2-e5733be03fe8omero.api.RawPixelsStore(omero.api._RawPixelsStoreTie@bb45f8eb)
2016-09-07 12:25:55,241 INFO  [                      omero.cmd.SessionI] (rver-21928) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/fe2f56be-51e2-430b-a9b7-8b4ffd15135aomero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@c0f16352)
2016-09-07 12:25:55,241 INFO  [                      omero.cmd.SessionI] (rver-21926) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IRepositoryInfo(omero.api._IRepositoryInfoTie@dd2c2125)
2016-09-07 12:25:55,241 INFO  [                      omero.cmd.SessionI] (rver-21930) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IContainer(omero.api._IContainerTie@79619f5d)
2016-09-07 12:25:55,241 INFO  [                      omero.cmd.SessionI] (rver-21925) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IRenderingSettings(omero.api._IRenderingSettingsTie@9b72b392)
2016-09-07 12:25:55,241 INFO  [                      omero.cmd.SessionI] (rver-21931) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.MetadataStore(omero.api._MetadataStoreTie@5e82dd53)
2016-09-07 12:25:55,257 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (rver-21928) Add callback: ]lQ_|Ug?P7a\'M\'dD9B*s/52cdd22f-6b72-451c-945c-e9353210510a
2016-09-07 12:25:55,273 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (rver-21926) getRequest: ome.services.blitz.repo.ManagedImportRequestI@6420412d
2016-09-07 12:25:55,273 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (rver-21930) getResponse: null
2016-09-07 12:25:55,273 INFO  [                loci.formats.ImageReader] (2-thread-4) LEOReader initializing \\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\CK-067-01-SL1344-25-SiiE-001.tif
2016-09-07 12:25:55,273 INFO  [       loci.formats.in.MinimalTiffReader] (2-thread-4) Reading IFDs
2016-09-07 12:25:55,273 INFO  [       loci.formats.in.MinimalTiffReader] (2-thread-4) Populating metadata
2016-09-07 12:25:55,273 INFO  [                      omero.cmd.SessionI] (rver-21925) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.IMetadata(omero.api._IMetadataTie@a2e650f2)
2016-09-07 12:25:55,273 INFO  [          loci.formats.in.BaseTiffReader] (2-thread-4) Populating OME metadata
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.IQuery.findAll
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[class ome.model.enums.Correction, null]
2016-09-07 12:25:55,273 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,273 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955273] time[2] tag[omero.call.success.ome.logic.QueryImpl.findAll]
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	(ome.model.enums.Correction:Id_1, ome.model.enums.Correction:Id_2, ome.model.enums.Correction:Id_3, ... 12 more)
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Meth:	interface ome.api.IQuery.findAll
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[class ome.model.enums.Immersion, null]
2016-09-07 12:25:55,273 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,273 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955273] time[2] tag[omero.call.success.ome.logic.QueryImpl.findAll]
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	(ome.model.enums.Immersion:Id_1, ome.model.enums.Immersion:Id_2, ome.model.enums.Immersion:Id_3, ... 5 more)
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Meth:	interface ome.api.IQuery.findAll
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	[class ome.model.enums.Format, null]
2016-09-07 12:25:55,273 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IMetadata.loadLogFiles
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[class ome.model.fs.Fileset, (2427)]
2016-09-07 12:25:55,273 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243955273] time[3] tag[omero.call.success.ome.logic.QueryImpl.findAll]
2016-09-07 12:25:55,273 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	(ome.model.enums.Format:Id_1, ome.model.enums.Format:Id_2, ome.model.enums.Format:Id_3, ... 218 more)
2016-09-07 12:25:55,273 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.IQuery.findAll
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[class ome.model.enums.DimensionOrder, null]
2016-09-07 12:25:55,288 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,288 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243955288] time[2] tag[omero.call.success.ome.logic.QueryImpl.findAll]
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	(ome.model.enums.DimensionOrder:Id_1, ome.model.enums.DimensionOrder:Id_2, ome.model.enums.DimensionOrder:Id_3, ... 3 more)
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Meth:	interface ome.api.IQuery.findAll
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	[class ome.model.enums.PixelsType, null]
2016-09-07 12:25:55,288 INFO  [         ome.security.basic.EventHandler] (rver-21925)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,288 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955288] time[2] tag[omero.call.success.ome.logic.QueryImpl.findAll]
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Rslt:	(ome.model.enums.PixelsType:Id_1, ome.model.enums.PixelsType:Id_2, ome.model.enums.PixelsType:Id_5, ... 8 more)
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.IQuery.findAll
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[class ome.model.enums.PhotometricInterpretation, null]
2016-09-07 12:25:55,288 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,288 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955288] time[2] tag[omero.call.success.ome.logic.QueryImpl.findAll]
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	(ome.model.enums.PhotometricInterpretation:Id_1, ome.model.enums.PhotometricInterpretation:Id_2, ome.model.enums.PhotometricInterpretation:Id_3, ... 3 more)
2016-09-07 12:25:55,288 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955273] time[17] tag[omero.call.success.ome.logic.MetadataImpl.loadLogFiles]
2016-09-07 12:25:55,288 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	{2427=(ome.model.core.OriginalFile:Id_15752)}
2016-09-07 12:25:55,304 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Meth:	interface ome.api.StatefulServiceInterface.close
2016-09-07 12:25:55,304 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	()
2016-09-07 12:25:55,304 DEBUG [                   loci.formats.Memoizer] (2-thread-4) saved to temp file: \\FSSFB-IMG\OmeroData$\BioFormatsCache\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\.CK-067-01-SL1344-25-SiiE-001.tif.bfmemo6726630651928268069
2016-09-07 12:25:55,304 DEBUG [                   loci.formats.Memoizer] (2-thread-4) start[1473243955288] time[18] tag[loci.formats.Memoizer.saveMemo]
2016-09-07 12:25:55,320 DEBUG [                   loci.formats.Memoizer] (2-thread-4) saved memo file: \\FSSFB-IMG\OmeroData$\BioFormatsCache\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\.CK-067-01-SL1344-25-SiiE-001.tif.bfmemo (62303 bytes)
2016-09-07 12:25:55,320 DEBUG [                   loci.formats.Memoizer] (2-thread-4) start[1473243955241] time[72] tag[loci.formats.Memoizer.setId]
2016-09-07 12:25:55,320 INFO  [    o.s.blitz.repo.ManagedImportRequestI] (2-thread-4) File format: LEO
2016-09-07 12:25:55,320 INFO  [    o.s.blitz.repo.ManagedImportRequestI] (2-thread-4) Base reader: loci.formats.in.LEOReader
2016-09-07 12:25:55,320 WARN  [    ome.formats.model.ReferenceProcessor] (2-thread-4) Unknown target class for LSID: Image:0
2016-09-07 12:25:55,320 INFO  [    ome.formats.OMEROMetadataStoreClient] (2-thread-4) Handling # of containers: 6
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Executor.doWork -- ome.services.blitz.impl.MetadataStoreI.updateObjects[]
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,320 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,320 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243955320] time[1] tag[omero.call.success.ome.services.blitz.impl.MetadataStoreI$4.doWork]
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	null
2016-09-07 12:25:55,320 INFO  [    ome.formats.OMEROMetadataStoreClient] (2-thread-4) Handling # of references: 1
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Executor.doWork -- ome.services.blitz.impl.MetadataStoreI.updateReferences[]
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,320 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,320 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243955320] time[1] tag[omero.call.success.ome.services.blitz.impl.MetadataStoreI$5.doWork]
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	null
2016-09-07 12:25:55,320 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=209544(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Executor.doWork -- ome.services.blitz.impl.MetadataStoreI.saveToDb[]
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,320 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955304] time[21] tag[omero.call.success.ome.services.RawFileBean.close]
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	null
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21929) Cleanup: ome.services.RawFileBean@416868e3
2016-09-07 12:25:55,320 INFO  [                      omero.cmd.SessionI] (rver-21929) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/357897f9-279f-4edf-8765-ae922186926aomero.api.RawFileStore(omero.api._RawFileStoreTie@d47acd43)
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.StatefulServiceInterface.close
2016-09-07 12:25:55,320 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	()
2016-09-07 12:25:55,335 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209546(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,335 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955320] time[6] tag[omero.call.success.ome.services.RawPixelsBean.close]
2016-09-07 12:25:55,335 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:55,335 INFO  [        ome.services.util.ServiceHandler] (rver-21931) Cleanup: ome.services.RawPixelsBean@47dac3d6
2016-09-07 12:25:55,335 INFO  [                      omero.cmd.SessionI] (rver-21931) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/ccd0383a-5fac-4e5c-8e24-9b8c6b5ac24fomero.api.RawPixelsStore(omero.api._RawPixelsStoreTie@a631baf5)
2016-09-07 12:25:55,335 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.StatefulServiceInterface.close
2016-09-07 12:25:55,335 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	()
2016-09-07 12:25:55,335 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,335 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955335] time[0] tag[omero.call.success.ome.services.ThumbnailBean.close]
2016-09-07 12:25:55,335 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	null
2016-09-07 12:25:55,335 INFO  [        ome.services.util.ServiceHandler] (rver-21930) Cleanup: ome.services.ThumbnailBean@2dad655c
2016-09-07 12:25:55,335 INFO  [                      omero.cmd.SessionI] (rver-21930) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/47850c80-f169-4bcb-8b4e-240af06fa733omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@ce99bc5e)
2016-09-07 12:25:55,335 INFO  [                      omero.cmd.SessionI] (rver-21928) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/cf1281d1-cd08-48f5-9b93-340c2f8cbb45omero.api.MetadataStore(omero.api._MetadataStoreTie@a3d46356)
2016-09-07 12:25:55,335 INFO  [         ome.security.basic.EventHandler] (rver-21925)  Auth:	user=52,group=3,event=209545(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,351 INFO  [       ome.security.basic.CurrentDetails] (rver-21925) Adding log:INSERT,class ome.model.acquisition.Instrument,1807
2016-09-07 12:25:55,351 INFO  [       ome.security.basic.CurrentDetails] (rver-21925) Adding log:INSERT,class ome.model.acquisition.Objective,1751
2016-09-07 12:25:55,351 INFO  [       ome.security.basic.CurrentDetails] (rver-21925) Adding log:INSERT,class ome.model.core.Image,2780
2016-09-07 12:25:55,366 INFO  [       ome.security.basic.CurrentDetails] (rver-21925) Adding log:INSERT,class ome.model.containers.DatasetImageLink,2948
2016-09-07 12:25:55,366 INFO  [       ome.security.basic.CurrentDetails] (rver-21925) Adding log:INSERT,class ome.model.core.Pixels,2780
2016-09-07 12:25:55,366 INFO  [       ome.security.basic.CurrentDetails] (rver-21925) Adding log:INSERT,class ome.model.core.LogicalChannel,3762
2016-09-07 12:25:55,382 INFO  [       ome.security.basic.CurrentDetails] (rver-21925) Adding log:INSERT,class ome.model.core.Channel,4262
2016-09-07 12:25:55,382 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955335] time[42] tag[omero.saveImportGraph]
2016-09-07 12:25:55,382 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955320] time[69] tag[omero.call.success.ome.services.blitz.impl.MetadataStoreI$3.doWork]
2016-09-07 12:25:55,382 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Rslt:	{Image=(ome.model.core.Image:Id_2780), Pixels=(ome.model.core.Pixels:Id_2780), Plate=null}
2016-09-07 12:25:55,382 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.IQuery.get
2016-09-07 12:25:55,382 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[class ome.model.core.OriginalFile, 15752]
2016-09-07 12:25:55,382 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,398 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243955382] time[6] tag[omero.call.success.ome.logic.QueryImpl.get]
2016-09-07 12:25:55,398 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	ome.model.core.OriginalFile:Id_15752
2016-09-07 12:25:55,398 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Meth:	interface ome.api.IUpdate.saveObject
2016-09-07 12:25:55,398 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[ome.model.core.OriginalFile:Id_15752]
2016-09-07 12:25:55,398 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=209547(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,398 INFO  [       ome.security.basic.CurrentDetails] (rver-21929) Adding log:UPDATE,class ome.model.core.OriginalFile,15752
2016-09-07 12:25:55,413 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955398] time[10] tag[omero.call.success.ome.logic.UpdateImpl.saveObject]
2016-09-07 12:25:55,413 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	null
2016-09-07 12:25:55,413 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1473243955320] time[93] tag[omero.request.step.0]
2016-09-07 12:25:55,413 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (2-thread-4) notify step 1 of 5
2016-09-07 12:25:55,413 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Executor.doWork -- ome.services.blitz.impl.MetadataStoreI.setPixelsParams[]
2016-09-07 12:25:55,413 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,429 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209548(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,429 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955413] time[15] tag[omero.call.success.ome.services.blitz.impl.MetadataStoreI$7.doWork]
2016-09-07 12:25:55,429 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:55,429 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IUpdate.saveArray
2016-09-07 12:25:55,429 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[[ome.model.core.Pixels:Id_2780]]
2016-09-07 12:25:55,445 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=209549(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,663 INFO  [       ome.security.basic.CurrentDetails] (rver-21930) Adding log:UPDATE,class ome.model.core.Pixels,2780
2016-09-07 12:25:55,663 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955429] time[223] tag[omero.call.success.ome.logic.UpdateImpl.saveArray]
2016-09-07 12:25:55,663 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	null
2016-09-07 12:25:55,663 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Executor.doWork -- ome.services.blitz.impl.MetadataStoreI.populateMinMax[]
2016-09-07 12:25:55,663 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,663 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=209550(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,663 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243955663] time[9] tag[omero.call.success.ome.services.blitz.impl.MetadataStoreI$2.doWork]
2016-09-07 12:25:55,663 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	null
2016-09-07 12:25:55,663 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Meth:	interface ome.api.IQuery.get
2016-09-07 12:25:55,663 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	[class ome.model.core.OriginalFile, 15752]
2016-09-07 12:25:55,663 INFO  [         ome.security.basic.EventHandler] (rver-21925)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,663 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955663] time[1] tag[omero.call.success.ome.logic.QueryImpl.get]
2016-09-07 12:25:55,663 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Rslt:	ome.model.core.OriginalFile:Id_15752
2016-09-07 12:25:55,679 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.IUpdate.saveObject
2016-09-07 12:25:55,679 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[ome.model.core.OriginalFile:Id_15752]
2016-09-07 12:25:55,679 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=209551(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,679 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243955679] time[7] tag[omero.call.success.ome.logic.UpdateImpl.saveObject]
2016-09-07 12:25:55,679 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	null
2016-09-07 12:25:55,679 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1473243955413] time[272] tag[omero.request.step.1]
2016-09-07 12:25:55,679 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (2-thread-4) notify step 2 of 5
2016-09-07 12:25:55,679 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Meth:	interface ome.api.IRenderingSettings.resetDefaultsInSet
2016-09-07 12:25:55,679 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[class ome.model.core.Pixels, (2780)]
2016-09-07 12:25:55,679 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=209552(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,695 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955679] time[3] tag[omero.loadPixels]
2016-09-07 12:25:55,710 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955695] time[12] tag[omero.loadRenderingSettingsByUser]
2016-09-07 12:25:55,710 INFO  [             ome.io.nio.FilePathResolver] (rver-21929) Metadata only file, resulting path: \\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\CK-067-01-SL1344-25-SiiE-001.tif
2016-09-07 12:25:55,726 DEBUG [                   loci.formats.Memoizer] (rver-21929) loaded memo file: \\FSSFB-IMG\OmeroData$\BioFormatsCache\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\.CK-067-01-SL1344-25-SiiE-001.tif.bfmemo (62303 bytes)
2016-09-07 12:25:55,726 DEBUG [                   loci.formats.Memoizer] (rver-21929) start[1473243955710] time[15] tag[loci.formats.Memoizer.loadMemo]
2016-09-07 12:25:55,726 DEBUG [                   loci.formats.Memoizer] (rver-21929) start[1473243955710] time[16] tag[loci.formats.Memoizer.setId]
2016-09-07 12:25:55,726 INFO  [                ome.io.nio.PixelsService] (rver-21929) Creating BfPixelBuffer: \\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\CK-067-01-SL1344-25-SiiE-001.tif Series: 0
2016-09-07 12:25:55,726 INFO  [       ome.security.basic.CurrentDetails] (rver-21929) Adding log:INSERT,class ome.model.display.QuantumDef,2780
2016-09-07 12:25:55,726 INFO  [       ome.security.basic.CurrentDetails] (rver-21929) Adding log:INSERT,class ome.model.display.RenderingDef,2780
2016-09-07 12:25:55,726 INFO  [       ome.security.basic.CurrentDetails] (rver-21929) Adding log:INSERT,class ome.model.display.ChannelBinding,4262
2016-09-07 12:25:55,726 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955726] time[4] tag[omero.resetDefaultsInSet.saveAndReturn]
2016-09-07 12:25:55,726 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955679] time[44] tag[omero.resetDefaultsInSet]
2016-09-07 12:25:55,726 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955679] time[51] tag[omero.call.success.ome.logic.RenderingSettingsImpl.resetDefaultsInSet]
2016-09-07 12:25:55,726 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	(2780)
2016-09-07 12:25:55,726 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.ThumbnailStore.createThumbnailsByLongestSideSet
2016-09-07 12:25:55,726 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[96, (2780)]
2016-09-07 12:25:55,741 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=209553(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955741] time[2] tag[omero.bulkLoadRenderingSettings]
2016-09-07 12:25:55,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955741] time[0] tag[omero.createAndPrepareMissingRenderingSettings]
2016-09-07 12:25:55,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955741] time[3] tag[omero.bulkLoadMetadata]
2016-09-07 12:25:55,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955741] time[3] tag[omero.loadMetadataByDimensionPool]
2016-09-07 12:25:55,741 INFO  [               ome.services.ThumbnailCtx] (rver-21931) New thumbnail object set size: 1
2016-09-07 12:25:55,741 INFO  [               ome.services.ThumbnailCtx] (rver-21931) Dimension pool size: 1
2016-09-07 12:25:55,741 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:INSERT,class ome.model.display.Thumbnail,2780
2016-09-07 12:25:55,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955741] time[1] tag[omero.bulkLoadMetadata]
2016-09-07 12:25:55,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955741] time[1] tag[omero.loadMetadataByDimensionPool]
2016-09-07 12:25:55,741 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955741] time[2] tag[omero.createMissingThumbnailMetadata]
2016-09-07 12:25:55,757 INFO  [             ome.io.nio.FilePathResolver] (rver-21931) Metadata only file, resulting path: \\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\CK-067-01-SL1344-25-SiiE-001.tif
2016-09-07 12:25:55,773 DEBUG [                   loci.formats.Memoizer] (rver-21931) loaded memo file: \\FSSFB-IMG\OmeroData$\BioFormatsCache\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\.CK-067-01-SL1344-25-SiiE-001.tif.bfmemo (62303 bytes)
2016-09-07 12:25:55,773 DEBUG [                   loci.formats.Memoizer] (rver-21931) start[1473243955757] time[12] tag[loci.formats.Memoizer.loadMemo]
2016-09-07 12:25:55,773 DEBUG [                   loci.formats.Memoizer] (rver-21931) start[1473243955757] time[13] tag[loci.formats.Memoizer.setId]
2016-09-07 12:25:55,773 INFO  [                ome.io.nio.PixelsService] (rver-21931) Creating BfPixelBuffer: \\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\CK-067-01-SL1344-25-SiiE-001.tif Series: 0
2016-09-07 12:25:55,773 INFO  [             omeis.providers.re.Renderer] (rver-21931) Enabling alphaless rendering.
2016-09-07 12:25:55,773 INFO  [             omeis.providers.re.Renderer] (rver-21931) Disabling PriColor rendering, channel color not primary.
2016-09-07 12:25:55,773 INFO  [             omeis.providers.re.Renderer] (rver-21931) Using: 'omeis.providers.re.GreyScaleStrategy' rendering strategy.
2016-09-07 12:25:55,773 INFO  [       ome.logic.JavaImageScalingService] (rver-21931) Scaling to: 72x96
2016-09-07 12:25:55,788 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955773] time[12] tag[java-image-scaling.resampleOp]
2016-09-07 12:25:55,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955741] time[56] tag[omero._createThumbnail]
2016-09-07 12:25:55,804 INFO  [       ome.security.basic.CurrentDetails] (rver-21931) Adding log:UPDATE,class ome.model.display.Thumbnail,2780
2016-09-07 12:25:55,804 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955726] time[75] tag[omero.call.success.ome.services.ThumbnailBean.createThumbnailsByLongestSideSet]
2016-09-07 12:25:55,804 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	null
2016-09-07 12:25:55,804 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IQuery.get
2016-09-07 12:25:55,804 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[class ome.model.core.OriginalFile, 15752]
2016-09-07 12:25:55,804 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,804 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955804] time[1] tag[omero.call.success.ome.logic.QueryImpl.get]
2016-09-07 12:25:55,804 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	ome.model.core.OriginalFile:Id_15752
2016-09-07 12:25:55,804 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Meth:	interface ome.api.IUpdate.saveObject
2016-09-07 12:25:55,804 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	[ome.model.core.OriginalFile:Id_15752]
2016-09-07 12:25:55,820 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=209554(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,820 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243955804] time[7] tag[omero.call.success.ome.logic.UpdateImpl.saveObject]
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	null
2016-09-07 12:25:55,820 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1473243955679] time[138] tag[omero.request.step.2]
2016-09-07 12:25:55,820 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (2-thread-4) notify step 3 of 5
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Executor.doWork -- ome.services.blitz.impl.MetadataStoreI.postProcess[]
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	[null, InternalSF@1036283684]
2016-09-07 12:25:55,820 INFO  [         ome.security.basic.EventHandler] (rver-21925)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,820 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955820] time[0] tag[omero.call.success.ome.services.blitz.impl.MetadataStoreI$6.doWork]
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Rslt:	null
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.IQuery.get
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[class ome.model.core.OriginalFile, 15752]
2016-09-07 12:25:55,820 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,820 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243955820] time[1] tag[omero.call.success.ome.logic.QueryImpl.get]
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	ome.model.core.OriginalFile:Id_15752
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Meth:	interface ome.api.IUpdate.saveObject
2016-09-07 12:25:55,820 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	[ome.model.core.OriginalFile:Id_15752]
2016-09-07 12:25:55,820 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=209555(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,835 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243955820] time[6] tag[omero.call.success.ome.logic.UpdateImpl.saveObject]
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	null
2016-09-07 12:25:55,835 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1473243955820] time[10] tag[omero.request.step.3]
2016-09-07 12:25:55,835 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (2-thread-4) notify step 4 of 5
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Meth:	interface ome.api.IQuery.get
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Args:	[class ome.model.core.OriginalFile, 15752]
2016-09-07 12:25:55,835 INFO  [         ome.security.basic.EventHandler] (rver-21931)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,835 INFO  [                 org.perf4j.TimingLogger] (rver-21931) start[1473243955835] time[1] tag[omero.call.success.ome.logic.QueryImpl.get]
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21931)  Rslt:	ome.model.core.OriginalFile:Id_15752
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IUpdate.saveObject
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[ome.model.core.OriginalFile:Id_15752]
2016-09-07 12:25:55,835 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=209556(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,835 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955835] time[6] tag[omero.call.success.ome.logic.UpdateImpl.saveObject]
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	null
2016-09-07 12:25:55,835 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1473243955835] time[9] tag[omero.request.step.4]
2016-09-07 12:25:55,835 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (2-thread-4) notify step 5 of 5
2016-09-07 12:25:55,835 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1473243955195] time[634] tag[omero.request]
2016-09-07 12:25:55,835 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1473243955195] time[640] tag[omero.call.success.omero.cmd.HandleI$1.doWork]
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (2-thread-4)  Rslt:	({Image=(omero.model.ImageI@704a6d09), Pixels=(omero.model.PixelsI@7129f257), Plate=()}, null, null, ... 2 more)
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Meth:	interface ome.api.StatefulServiceInterface.close
2016-09-07 12:25:55,835 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Args:	()
2016-09-07 12:25:55,851 INFO  [         ome.security.basic.EventHandler] (rver-21928)  Auth:	user=52,group=3,event=209557(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,851 INFO  [                 org.perf4j.TimingLogger] (rver-21928) start[1473243955835] time[6] tag[omero.call.success.ome.services.RawFileBean.close]
2016-09-07 12:25:55,851 INFO  [        ome.services.util.ServiceHandler] (rver-21928)  Rslt:	null
2016-09-07 12:25:55,851 INFO  [        ome.services.util.ServiceHandler] (rver-21928) Cleanup: ome.services.RawFileBean@b60bd33
2016-09-07 12:25:55,851 INFO  [                      omero.cmd.SessionI] (rver-21928) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/ec06403e-6a10-4f94-9aa4-14139e2409c7omero.api.RawFileStore(omero.api._RawFileStoreTie@dabcb8ad)
2016-09-07 12:25:55,851 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Meth:	interface ome.api.StatefulServiceInterface.close
2016-09-07 12:25:55,851 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	()
2016-09-07 12:25:55,851 INFO  [         ome.security.basic.EventHandler] (rver-21925)  Auth:	user=52,group=3,event=209558(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,851 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955851] time[5] tag[omero.call.success.ome.services.RawPixelsBean.close]
2016-09-07 12:25:55,851 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Rslt:	null
2016-09-07 12:25:55,851 INFO  [        ome.services.util.ServiceHandler] (rver-21925) Cleanup: ome.services.RawPixelsBean@24f4fa72
2016-09-07 12:25:55,851 INFO  [                      omero.cmd.SessionI] (rver-21925) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/1907d356-33bd-4bf3-86d2-e5733be03fe8omero.api.RawPixelsStore(omero.api._RawPixelsStoreTie@bb45f8eb)
2016-09-07 12:25:55,851 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.StatefulServiceInterface.close
2016-09-07 12:25:55,851 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	()
2016-09-07 12:25:55,851 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,867 DEBUG [                   loci.formats.Memoizer] (rver-21926) loaded memo file: \\FSSFB-IMG\OmeroData$\BioFormatsCache\FSSFB-IMG\OmeroData$\ManagedRepository\Kunis_52\2016-09\07\12-25-54.741\.CK-067-01-SL1344-25-SiiE-001.tif.bfmemo (62303 bytes)
2016-09-07 12:25:55,867 DEBUG [                   loci.formats.Memoizer] (rver-21926) start[1473243955851] time[12] tag[loci.formats.Memoizer.loadMemo]
2016-09-07 12:25:55,867 DEBUG [                   loci.formats.Memoizer] (rver-21926) start[1473243955851] time[13] tag[loci.formats.Memoizer.setId]
2016-09-07 12:25:55,867 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243955851] time[14] tag[omero.call.success.ome.services.ThumbnailBean.close]
2016-09-07 12:25:55,867 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	null
2016-09-07 12:25:55,867 INFO  [        ome.services.util.ServiceHandler] (rver-21926) Cleanup: ome.services.ThumbnailBean@469c4a70
2016-09-07 12:25:55,867 INFO  [                      omero.cmd.SessionI] (rver-21926) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/fe2f56be-51e2-430b-a9b7-8b4ffd15135aomero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@c0f16352)
2016-09-07 12:25:55,867 INFO  [                      omero.cmd.SessionI] (rver-21929) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.MetadataStore(omero.api._MetadataStoreTie@5e82dd53)
2016-09-07 12:25:55,867 INFO  [    o.s.blitz.repo.ManagedImportRequestI] (2-thread-4) Finalizing log file.
2016-09-07 12:25:55,867 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IQuery.get
2016-09-07 12:25:55,867 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[class ome.model.core.OriginalFile, 15752]
2016-09-07 12:25:55,867 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,867 INFO  [                 org.perf4j.TimingLogger] (rver-21930) start[1473243955867] time[1] tag[omero.call.success.ome.logic.QueryImpl.get]
2016-09-07 12:25:55,867 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	ome.model.core.OriginalFile:Id_15752
2016-09-07 12:25:55,867 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Meth:	interface ome.api.IUpdate.saveObject
2016-09-07 12:25:55,867 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	[ome.model.core.OriginalFile:Id_15752]
2016-09-07 12:25:55,867 INFO  [         ome.security.basic.EventHandler] (rver-21925)  Auth:	user=52,group=3,event=209559(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,882 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955867] time[6] tag[omero.call.success.ome.logic.UpdateImpl.saveObject]
2016-09-07 12:25:55,882 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Rslt:	null
2016-09-07 12:25:55,882 INFO  [o.services.sessions.SessionContext$Count] (rver-21926) -Reference count: a3ca7472-f3d2-4250-bb0b-859e614e690f=2
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) cleanupSelf(ServiceFactoryI(session-71128637-d696-45dc-9f21-163c1feae518/a3ca7472-f3d2-4250-bb0b-859e614e690f)).
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IAdmin(omero.api._IAdminTie@c6115469)
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Removed servant from adapter: 71128637-d696-45dc-9f21-163c1feae518omero.api.IAdmin
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IQuery(omero.api._IQueryTie@d5f57b29)
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Removed servant from adapter: 71128637-d696-45dc-9f21-163c1feae518omero.api.IQuery
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IRepositoryInfo(omero.api._IRepositoryInfoTie@dd2c2125)
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Removed servant from adapter: 71128637-d696-45dc-9f21-163c1feae518omero.api.IRepositoryInfo
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IUpdate(omero.api._IUpdateTie@c60fc948)
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Removed servant from adapter: 71128637-d696-45dc-9f21-163c1feae518omero.api.IUpdate
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IContainer(omero.api._IContainerTie@79619f5d)
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Removed servant from adapter: 71128637-d696-45dc-9f21-163c1feae518omero.api.IContainer
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/71128637-d696-45dc-9f21-163c1feae518omero.api.IRenderingSettings(omero.api._IRenderingSettingsTie@9b72b392)
2016-09-07 12:25:55,882 INFO  [                      omero.cmd.SessionI] (rver-21926) Removed servant from adapter: 71128637-d696-45dc-9f21-163c1feae518omero.api.IRenderingSettings
2016-09-07 12:25:55,882 INFO  [                 org.perf4j.TimingLogger] (2-thread-4) start[1473243955195] time[678] tag[omero.request.tx]
2016-09-07 12:25:55,882 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (2-thread-4) notify finished: omero.grid.ImportResponse@60fc1faf/omero.cmd.Status@5789f081
2016-09-07 12:25:55,976 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (rver-21929) getRequest: ome.services.blitz.repo.ManagedImportRequestI@6420412d
2016-09-07 12:25:55,976 INFO  [                      omero.cmd.SessionI] (rver-21931) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/IHandle6ec18e8d-e24f-4f37-994c-664991053448(omero.cmd._HandleTie@83ca13a6)
2016-09-07 12:25:55,976 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (rver-21931) Closing...
2016-09-07 12:25:55,976 INFO  [ o.s.b.r.ManagedImportRequestI.@6420412d] (rver-21931) notify finished: omero.grid.ImportResponse@60fc1faf/omero.cmd.Status@5789f081
2016-09-07 12:25:55,976 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Meth:	interface ome.api.IContainer.loadContainerHierarchy
2016-09-07 12:25:55,976 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Args:	[class ome.model.containers.Project, (), PARAMS:O[O]]
2016-09-07 12:25:55,992 INFO  [         ome.security.basic.EventHandler] (rver-21930)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,992 INFO  [                      omero.cmd.SessionI] (rver-21928) Added servant to adapter: a3ca7472-f3d2-4250-bb0b-859e614e690f/d116aa08-f2e3-4f45-96bf-249f83e1a867omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@d3605b5a)
2016-09-07 12:25:55,992 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Meth:	interface ome.api.ThumbnailStore.setPixelsId
2016-09-07 12:25:55,992 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Args:	[2780]
2016-09-07 12:25:55,992 INFO  [         ome.security.basic.EventHandler] (rver-21925)  Auth:	user=52,group=3,event=209560(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:55,992 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955992] time[2] tag[omero.bulkLoadRenderingSettings]
2016-09-07 12:25:55,992 INFO  [                 org.perf4j.TimingLogger] (rver-21925) start[1473243955992] time[7] tag[omero.call.success.ome.services.ThumbnailBean.setPixelsId]
2016-09-07 12:25:55,992 INFO  [        ome.services.util.ServiceHandler] (rver-21925)  Rslt:	true
2016-09-07 12:25:55,992 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Meth:	interface ome.api.ThumbnailStore.getThumbnail
2016-09-07 12:25:55,992 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Args:	[96, 72]
2016-09-07 12:25:56,007 INFO  [         ome.security.basic.EventHandler] (rver-21926)  Auth:	user=52,group=3,event=209561(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:56,007 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243956007] time[1] tag[omero.bulkLoadMetadata]
2016-09-07 12:25:56,007 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243956007] time[1] tag[omero.loadMetadataByDimensionPool]
2016-09-07 12:25:56,007 INFO  [               ome.services.ThumbnailCtx] (rver-21926) New thumbnail object set size: 0
2016-09-07 12:25:56,007 INFO  [               ome.services.ThumbnailCtx] (rver-21926) Dimension pool size: 0
2016-09-07 12:25:56,007 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243956007] time[0] tag[omero.createMissingThumbnailMetadata]
2016-09-07 12:25:56,007 INFO  [                 org.perf4j.TimingLogger] (rver-21926) start[1473243955992] time[6] tag[omero.call.success.ome.services.ThumbnailBean.getThumbnail]
2016-09-07 12:25:56,007 INFO  [        ome.services.util.ServiceHandler] (rver-21926)  Rslt:	[-1, -40, -1, ... 1 more]
2016-09-07 12:25:56,007 INFO  [        ome.services.util.ServiceHandler] (rver-21930)  Rslt:	(ome.model.containers.Project:Id_351, ome.model.containers.Project:Id_2, ome.model.containers.Dataset:Id_1456, ... 14 more)
2016-09-07 12:25:56,023 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Meth:	interface ome.api.StatefulServiceInterface.close
2016-09-07 12:25:56,023 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Args:	()
2016-09-07 12:25:56,023 INFO  [         ome.security.basic.EventHandler] (rver-21929)  Auth:	user=52,group=3,event=null(User),sess=a3ca7472-f3d2-4250-bb0b-859e614e690f
2016-09-07 12:25:56,023 INFO  [                 org.perf4j.TimingLogger] (rver-21929) start[1473243956023] time[0] tag[omero.call.success.ome.services.ThumbnailBean.close]
2016-09-07 12:25:56,023 INFO  [        ome.services.util.ServiceHandler] (rver-21929)  Rslt:	null
2016-09-07 12:25:56,023 INFO  [        ome.services.util.ServiceHandler] (rver-21929) Cleanup: ome.services.ThumbnailBean@4285d456
2016-09-07 12:25:56,023 INFO  [                      omero.cmd.SessionI] (rver-21929) Unregistered servant:a3ca7472-f3d2-4250-bb0b-859e614e690f/d116aa08-f2e3-4f45-96bf-249f83e1a867omero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@d3605b5a)
2016-09-07 12:26:14,509 INFO  [ome.services.sessions.state.SessionCache] (2-thread-5) Synchronizing session cache. Count = 7