2011-07-07 11:13:46,571 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1310055226559] time[12] tag[omero.call.success.ome.logic.AdminImpl.getEventContext] 2011-07-07 11:13:46,571 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: ome.system.SimpleEventContext@3c38a5d7 2011-07-07 11:13:46,574 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Meth: interface ome.api.IPixels.retrieveAllRndSettings 2011-07-07 11:13:46,574 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [1952, 52] 2011-07-07 11:13:46,574 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:13:46,593 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1310055226574] time[19] tag[omero.call.success.ome.logic.PixelsImpl.retrieveAllRndSettings] 2011-07-07 11:13:46,593 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: (ome.model.display.RenderingDef:Id_2551) 2011-07-07 11:14:08,467 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Meth: interface ome.api.IAdmin.getEventContext 2011-07-07 11:14:08,467 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Args: () 2011-07-07 11:14:08,469 INFO [ ome.security.basic.EventHandler] (l.Server-9) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:08,469 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(3821004f-b08d-49b5-af4b-4777fc533b6b) 2011-07-07 11:14:08,469 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Args: [null, InternalSF@764109536] 2011-07-07 11:14:08,470 INFO [ ome.security.basic.EventHandler] (2-thread-1) Auth: user=0,group=0,event=null(Sessions),sess=000e833c-b2a4-4522-8006-e77541704599 2011-07-07 11:14:08,479 INFO [ org.perf4j.TimingLogger] (2-thread-1) start[1310055248469] time[10] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork] 2011-07-07 11:14:08,479 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Rslt: (ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_53, [1, 0, 53, 4, 153, 103], ... 4 more) 2011-07-07 11:14:08,482 INFO [ org.perf4j.TimingLogger] (l.Server-9) start[1310055248468] time[14] tag[omero.call.success.ome.logic.AdminImpl.getEventContext] 2011-07-07 11:14:08,482 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Rslt: ome.system.SimpleEventContext@448dbd82 2011-07-07 11:14:08,486 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Meth: interface ome.api.IContainer.loadContainerHierarchy 2011-07-07 11:14:08,486 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Args: [class ome.model.containers.Project, (), PARAMS:F(u153):O[O]] 2011-07-07 11:14:08,487 INFO [ ome.security.basic.EventHandler] (l.Server-3) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:08,559 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1310055248486] time[73] tag[omero.call.success.ome.logic.PojosImpl.loadContainerHierarchy] 2011-07-07 11:14:08,559 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Rslt: (ome.model.containers.Project:Id_103, ome.model.containers.Project:Id_109, ome.model.containers.Project:Id_302, ... 1 more) 2011-07-07 11:14:08,636 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Meth: interface ome.api.IAdmin.getEventContext 2011-07-07 11:14:08,636 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Args: () 2011-07-07 11:14:08,637 INFO [ ome.security.basic.EventHandler] (l.Server-2) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:08,638 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(3821004f-b08d-49b5-af4b-4777fc533b6b) 2011-07-07 11:14:08,638 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Args: [null, InternalSF@764109536] 2011-07-07 11:14:08,639 INFO [ ome.security.basic.EventHandler] (2-thread-1) Auth: user=0,group=0,event=null(Sessions),sess=000e833c-b2a4-4522-8006-e77541704599 2011-07-07 11:14:08,649 INFO [ org.perf4j.TimingLogger] (2-thread-1) start[1310055248638] time[11] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork] 2011-07-07 11:14:08,649 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Rslt: (ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_53, [1, 0, 53, 4, 153, 103], ... 4 more) 2011-07-07 11:14:08,651 INFO [ org.perf4j.TimingLogger] (l.Server-2) start[1310055248637] time[14] tag[omero.call.success.ome.logic.AdminImpl.getEventContext] 2011-07-07 11:14:08,651 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Rslt: ome.system.SimpleEventContext@1467056e 2011-07-07 11:14:08,654 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.IContainer.getCollectionCount 2011-07-07 11:14:08,655 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [ome.model.containers.Dataset, ome.model.containers.Dataset_imageLinks, (1, 201, 402, ... 22 more), null] 2011-07-07 11:14:08,656 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:08,708 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1310055248655] time[53] tag[omero.call.success.ome.logic.PojosImpl.getCollectionCount] 2011-07-07 11:14:08,708 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: {1=1, 201=1, 402=4, ... 22 more} 2011-07-07 11:14:12,384 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Meth: interface ome.api.IAdmin.getEventContext 2011-07-07 11:14:12,384 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Args: () 2011-07-07 11:14:12,385 INFO [ ome.security.basic.EventHandler] (l.Server-5) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:12,386 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(3821004f-b08d-49b5-af4b-4777fc533b6b) 2011-07-07 11:14:12,386 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Args: [null, InternalSF@764109536] 2011-07-07 11:14:12,387 INFO [ ome.security.basic.EventHandler] (2-thread-1) Auth: user=0,group=0,event=null(Sessions),sess=000e833c-b2a4-4522-8006-e77541704599 2011-07-07 11:14:12,398 INFO [ org.perf4j.TimingLogger] (2-thread-1) start[1310055252386] time[12] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork] 2011-07-07 11:14:12,398 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Rslt: (ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_53, [1, 0, 53, 4, 153, 103], ... 4 more) 2011-07-07 11:14:12,399 INFO [ org.perf4j.TimingLogger] (l.Server-5) start[1310055252384] time[15] tag[omero.call.success.ome.logic.AdminImpl.getEventContext] 2011-07-07 11:14:12,399 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Rslt: ome.system.SimpleEventContext@607df346 2011-07-07 11:14:12,413 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Meth: interface ome.api.IMetadata.loadAnnotations 2011-07-07 11:14:12,414 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Args: [class ome.model.containers.Project, (109), (), (), PARAMS] 2011-07-07 11:14:12,415 INFO [ ome.security.basic.EventHandler] (l.Server-0) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:12,425 INFO [ org.perf4j.TimingLogger] (l.Server-0) start[1310055252414] time[11] tag[omero.call.success.ome.logic.MetadataImpl.loadAnnotations] 2011-07-07 11:14:12,425 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Rslt: {109=()} 2011-07-07 11:14:17,124 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Meth: interface ome.api.IAdmin.getEventContext 2011-07-07 11:14:17,124 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Args: () 2011-07-07 11:14:17,125 INFO [ ome.security.basic.EventHandler] (l.Server-6) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:17,126 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(3821004f-b08d-49b5-af4b-4777fc533b6b) 2011-07-07 11:14:17,126 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Args: [null, InternalSF@764109536] 2011-07-07 11:14:17,127 INFO [ ome.security.basic.EventHandler] (2-thread-1) Auth: user=0,group=0,event=null(Sessions),sess=000e833c-b2a4-4522-8006-e77541704599 2011-07-07 11:14:17,139 INFO [ org.perf4j.TimingLogger] (2-thread-1) start[1310055257126] time[13] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork] 2011-07-07 11:14:17,139 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Rslt: (ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_53, [1, 0, 53, 4, 153, 103], ... 4 more) 2011-07-07 11:14:17,140 INFO [ org.perf4j.TimingLogger] (l.Server-6) start[1310055257124] time[16] tag[omero.call.success.ome.logic.AdminImpl.getEventContext] 2011-07-07 11:14:17,140 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Rslt: ome.system.SimpleEventContext@5b420a67 2011-07-07 11:14:17,150 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Meth: interface ome.api.IMetadata.loadAnnotations 2011-07-07 11:14:17,151 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [class ome.model.containers.Dataset, (258), (), (), PARAMS] 2011-07-07 11:14:17,152 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:17,157 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Meth: interface ome.api.IAdmin.getEventContext 2011-07-07 11:14:17,157 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Args: () 2011-07-07 11:14:17,159 INFO [ ome.security.basic.EventHandler] (l.Server-9) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:17,162 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(3821004f-b08d-49b5-af4b-4777fc533b6b) 2011-07-07 11:14:17,162 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Args: [null, InternalSF@764109536] 2011-07-07 11:14:17,163 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1310055257151] time[12] tag[omero.call.success.ome.logic.MetadataImpl.loadAnnotations] 2011-07-07 11:14:17,163 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: {258=()} 2011-07-07 11:14:17,163 INFO [ ome.security.basic.EventHandler] (2-thread-1) Auth: user=0,group=0,event=null(Sessions),sess=000e833c-b2a4-4522-8006-e77541704599 2011-07-07 11:14:17,172 INFO [ org.perf4j.TimingLogger] (2-thread-1) start[1310055257162] time[10] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork] 2011-07-07 11:14:17,172 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Rslt: (ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_53, [1, 0, 53, 4, 153, 103], ... 4 more) 2011-07-07 11:14:17,173 INFO [ org.perf4j.TimingLogger] (l.Server-9) start[1310055257158] time[15] tag[omero.call.success.ome.logic.AdminImpl.getEventContext] 2011-07-07 11:14:17,173 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Rslt: ome.system.SimpleEventContext@17fe67e4 2011-07-07 11:14:17,176 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Meth: interface ome.api.IContainer.loadContainerHierarchy 2011-07-07 11:14:17,177 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Args: [class ome.model.containers.Dataset, (258), PARAMS:O[L]] 2011-07-07 11:14:17,178 INFO [ ome.security.basic.EventHandler] (l.Server-3) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:17,295 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1310055257177] time[118] tag[omero.call.success.ome.logic.PojosImpl.loadContainerHierarchy] 2011-07-07 11:14:17,295 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Rslt: (ome.model.containers.Dataset:Id_258) 2011-07-07 11:14:17,485 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Meth: interface ome.api.IAdmin.getEventContext 2011-07-07 11:14:17,485 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Args: () 2011-07-07 11:14:17,486 INFO [ ome.security.basic.EventHandler] (l.Server-2) Auth: user=52,group=53,event=null(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:17,486 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(3821004f-b08d-49b5-af4b-4777fc533b6b) 2011-07-07 11:14:17,486 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Args: [null, InternalSF@764109536] 2011-07-07 11:14:17,487 INFO [ ome.security.basic.EventHandler] (2-thread-1) Auth: user=0,group=0,event=null(Sessions),sess=000e833c-b2a4-4522-8006-e77541704599 2011-07-07 11:14:17,506 INFO [ org.perf4j.TimingLogger] (2-thread-1) start[1310055257486] time[20] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork] 2011-07-07 11:14:17,507 INFO [ ome.services.util.ServiceHandler] (2-thread-1) Rslt: (ome.model.meta.Experimenter:Id_52, ome.model.meta.ExperimenterGroup:Id_53, [1, 0, 53, 4, 153, 103], ... 4 more) 2011-07-07 11:14:17,508 INFO [ org.perf4j.TimingLogger] (l.Server-2) start[1310055257485] time[23] tag[omero.call.success.ome.logic.AdminImpl.getEventContext] 2011-07-07 11:14:17,508 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Rslt: ome.system.SimpleEventContext@7c17b37 2011-07-07 11:14:17,524 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.ThumbnailStore.getThumbnailByLongestSideSet 2011-07-07 11:14:17,524 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [96, (1798, 1799, 1796, ... 9 more)] 2011-07-07 11:14:17,565 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=52,group=53,event=28750(User),sess=3821004f-b08d-49b5-af4b-4777fc533b6b 2011-07-07 11:14:17,602 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1310055257565] time[37] tag[omero.bulkLoadRenderingSettings] 2011-07-07 11:14:17,622 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1310055257603] time[19] tag[omero.loadMissingPixels] 2011-07-07 11:14:17,623 INFO [ ome.services.ThumbnailCtx] (l.Server-1) 7 pixels without settings 2011-07-07 11:14:17,675 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1310055257626] time[49] tag[omero.loadPixelsByImage] 2011-07-07 11:14:17,721 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1310055257675] time[46] tag[omero.loadRenderingSettingsByUser] 2011-07-07 11:14:17,744 INFO [ ome.io.nio.FilePathResolver] (l.Server-1) Metadata only file, resulting path: /OMERO/Files/Dir-003/3311 2011-07-07 11:14:17,744 INFO [ ome.io.nio.PixelsService] (l.Server-1) Using Pyramid BfPixelBuffer: /OMERO/Pixels/Dir-001/1788_pyramid 2011-07-07 11:14:17,802 INFO [ loci.formats.in.MinimalTiffReader] (l.Server-1) Reading IFDs 2011-07-07 11:14:18,899 INFO [ loci.formats.in.MinimalTiffReader] (l.Server-1) Populating metadata 2011-07-07 11:15:00,019 INFO [ ome.services.blitz.fire.SessionManagerI] (3-thread-4) Performing requestHeartbeats 2011-07-07 11:18:00,016 INFO [ ome.services.blitz.fire.SessionManagerI] (3-thread-1) Performing requestHeartbeats 2011-07-07 11:19:28,745 INFO [ ome.io.nio.FilePathResolver] (l.Server-1) Metadata only file, resulting path: /OMERO/Files/Dir-003/3313 2011-07-07 11:19:28,746 INFO [ ome.io.nio.PixelsService] (l.Server-1) Using Pyramid BfPixelBuffer: /OMERO/Pixels/Dir-001/1789_pyramid 2011-07-07 11:19:28,750 INFO [ loci.formats.in.MinimalTiffReader] (l.Server-1) Reading IFDs 2011-07-07 11:19:29,203 INFO [ loci.formats.in.MinimalTiffReader] (l.Server-1) Populating metadata