Warning: Can't synchronize with repository "(default)" (/home/git/ome.git does not appear to be a Git repository.). Look in the Trac log for more information.
Notice: In order to edit this ticket you need to be either: a Product Owner, The owner or the reporter of the ticket, or, in case of a Task not yet assigned, a team_member"

Task #10682 (closed)

Opened 11 years ago

Closed 9 years ago

Bug: trestle CMU-1

Reported by: jburel Owned by: mlinkert
Priority: critical Milestone: 5.x
Component: Web Version: 5.1.2
Keywords: n.a. Cc: fs@…, ux@…, mlinkert
Resources: n.a. Referenced By: n.a.
References: n.a. Remaining Time: n.a.
Sprint: FS demo 4.x

Description

Loading Tile for first resolution is fine.
Then selecting a new resolution level, the tiles loading is very slow. Tiles come after over 5mins.
No feedback.
user-4 ready-only

Change History (20)

comment:1 Changed 11 years ago by jamoore

  • Sprint changed from FS Demo 3 to FS Demo 4

Moving to next FS milestone for cleanup. If this is more appropriate elsewhere, please feel free to move it.

comment:2 Changed 11 years ago by jamoore

  • Sprint changed from FS demo 4.x to FS demo 4.1

will: is this also something you can look at this week?

comment:3 Changed 11 years ago by wmoore

sounds like a pretty core issue, but I can have a look.

comment:4 Changed 11 years ago by wmoore

  • Owner changed from web-team@… to wmoore

comment:5 Changed 11 years ago by wmoore

Have just tried 3 of the biggest CMU images on gretzky user-4.

  • CMU-2.ndpi [Series 1]
  • CMU-3.ndpi [Series 1]
  • CMU-1.ndpi [Series 1]

These all seem to be working fine at various zoom levels (quite quick actually).
The exception is CMU-1/CMU-1-40x - 2010-01-12 13.24.05.vms [CMU-1-40x - 2010-01-12 13.24.05.vms full resolution] which is not rendering tiles at-all. The tile size is 1024 x 1024 and at the initial zoom level (3.1 %, or '5') this is taking too long to render. Might work better with smaller tiles?

comment:6 Changed 11 years ago by jamoore

  • Cc mlinkert added

Any immediate ideas, Melissa?

comment:7 Changed 11 years ago by mlinkert

1024x1024 really is the optimal size; we've already been down the road of trying to reduce it without good results.

I don't have any immediate ideas, but if anyone has server logs that would help (especially if the tiles never appear).

comment:8 Changed 11 years ago by jamoore

  • Sprint changed from FS demo 4.1 to FS demo 4.x

Moved from sprint FS demo 4.1

comment:9 Changed 11 years ago by wmoore

  • Owner changed from wmoore to mlinkert

That particular image is slower than the others in Insight as well as web (it just times out in web) so this doesn't seem to be a web issue.

Server logs when opening the image in Insight (getting thumbnail for birds eve view and then a few tiles).
The gap shows where the client was waiting for tiles...

2013-05-22 10:35:27,848 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Meth:	interface ome.api.ThumbnailStore.getThumbnailByLongestSideSet
2013-05-22 10:35:27,849 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	[96, (40)]
2013-05-22 10:35:27,856 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:	user=5,group=8,event=3272(User),sess=e74cd8fd-7290-41fd-815c-ada9ea347d97
2013-05-22 10:35:27,859 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1369215327856] time[2] tag[omero.bulkLoadRenderingSettings]
2013-05-22 10:35:27,859 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1369215327859] time[0] tag[omero.createAndPrepareMissingRenderingSettings]
2013-05-22 10:35:27,861 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1369215327859] time[1] tag[omero.bulkLoadMetadata]
2013-05-22 10:35:27,861 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1369215327859] time[1] tag[omero.loadMetadataByDimensionPool]
2013-05-22 10:35:27,861 INFO  [               ome.services.ThumbnailCtx] (l.Server-2) New thumbnail object set size: 0
2013-05-22 10:35:27,861 INFO  [               ome.services.ThumbnailCtx] (l.Server-2) Dimension pool size: 0
2013-05-22 10:35:27,861 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1369215327861] time[0] tag[omero.createMissingThumbnailMetadata]
2013-05-22 10:35:27,870 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1369215327849] time[21] tag[omero.call.success.ome.services.ThumbnailBean.getThumbnailByLongestSideSet]
2013-05-22 10:35:27,870 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	{40=[-1, -40, -1, ... 1 more]}
2013-05-22 10:35:27,951 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Meth:	interface ome.api.IQuery.findAllByQuery
2013-05-22 10:35:27,951 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Args:	[select info from PlaneInfo as info where pixels.id = :id, PARAMS:id=40 ]
2013-05-22 10:35:27,951 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Meth:	interface omeis.providers.re.RenderingEngine.setResolutionLevel
2013-05-22 10:35:27,951 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Args:	[0]
2013-05-22 10:35:27,951 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215327951] time[0] tag[omero.call.success.ome.services.RenderingBean.setResolutionLevel]
2013-05-22 10:35:27,951 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Rslt:	null
2013-05-22 10:35:27,952 INFO  [         ome.security.basic.EventHandler] (.Server-10)  Auth:	user=5,group=8,event=null(User),sess=e74cd8fd-7290-41fd-815c-ada9ea347d97
2013-05-22 10:35:27,953 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface omeis.providers.re.RenderingEngine.setResolutionLevel
2013-05-22 10:35:27,953 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[0]
2013-05-22 10:35:27,953 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1369215327953] time[0] tag[omero.call.success.ome.services.RenderingBean.setResolutionLevel]
2013-05-22 10:35:27,953 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	null
2013-05-22 10:35:27,954 INFO  [                 org.perf4j.TimingLogger] (.Server-10) start[1369215327951] time[3] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2013-05-22 10:35:27,954 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Rslt:	()
2013-05-22 10:35:27,955 INFO  [        ome.services.util.ServiceHandler] (.Server-12)  Meth:	interface omeis.providers.re.RenderingEngine.setResolutionLevel
2013-05-22 10:35:27,955 INFO  [        ome.services.util.ServiceHandler] (.Server-12)  Args:	[0]
2013-05-22 10:35:27,955 INFO  [                 org.perf4j.TimingLogger] (.Server-12) start[1369215327955] time[0] tag[omero.call.success.ome.services.RenderingBean.setResolutionLevel]
2013-05-22 10:35:27,955 INFO  [        ome.services.util.ServiceHandler] (.Server-12)  Rslt:	null
2013-05-22 10:35:27,957 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Meth:	interface omeis.providers.re.RenderingEngine.setResolutionLevel
2013-05-22 10:35:27,957 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	[0]
2013-05-22 10:35:27,957 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1369215327957] time[0] tag[omero.call.success.ome.services.RenderingBean.setResolutionLevel]
2013-05-22 10:35:27,957 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	null
2013-05-22 10:35:27,961 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Meth:	interface omeis.providers.re.RenderingEngine.renderCompressed
2013-05-22 10:35:27,961 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Args:	[Type: XY, z=0, t=0; Region: x=0 y=0 width=1024 height=1024]
2013-05-22 10:35:27,961 INFO  [             omeis.providers.re.Renderer] (l.Server-0) Using: 'omeis.providers.re.HSBStrategy' rendering strategy.
2013-05-22 10:35:27,961 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Meth:	interface omeis.providers.re.RenderingEngine.renderCompressed
2013-05-22 10:35:27,961 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Args:	[Type: XY, z=0, t=0; Region: x=1024 y=0 width=896 height=1024]
2013-05-22 10:35:27,961 INFO  [             omeis.providers.re.Renderer] (.Server-10) Using: 'omeis.providers.re.HSBStrategy' rendering strategy.
2013-05-22 10:35:27,962 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface omeis.providers.re.RenderingEngine.renderCompressed
2013-05-22 10:35:27,962 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	[Type: XY, z=0, t=0; Region: x=1024 y=1024 width=896 height=896]
2013-05-22 10:35:27,962 INFO  [        ome.services.util.ServiceHandler] (.Server-12)  Meth:	interface omeis.providers.re.RenderingEngine.renderCompressed
2013-05-22 10:35:27,962 INFO  [             omeis.providers.re.Renderer] (l.Server-5) Using: 'omeis.providers.re.HSBStrategy' rendering strategy.
2013-05-22 10:35:27,962 INFO  [        ome.services.util.ServiceHandler] (.Server-12)  Args:	[Type: XY, z=0, t=0; Region: x=0 y=1024 width=1024 height=896]
2013-05-22 10:35:27,962 INFO  [             omeis.providers.re.Renderer] (.Server-12) Using: 'omeis.providers.re.HSBStrategy' rendering strategy.




2013-05-22 10:35:55,455 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-2) Found session locally: 69204ff6-beee-46aa-8b03-19121c18448c
2013-05-22 10:35:55,456 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-2) Rejoining session ServiceFactoryI(session-a6a86cdc-2896-428d-ae16-a1aa3948f188/69204ff6-beee-46aa-8b03-19121c18448c) (agent=OMERO.web)
2013-05-22 10:35:55,460 INFO  [o.services.sessions.SessionContext$Count] (.Server-13) -Reference count: 69204ff6-beee-46aa-8b03-19121c18448c=0
2013-05-22 10:35:55,460 INFO  [                      omero.cmd.SessionI] (.Server-13) cleanupSelf(ServiceFactoryI(session-a6a86cdc-2896-428d-ae16-a1aa3948f188/69204ff6-beee-46aa-8b03-19121c18448c)).
2013-05-22 10:35:56,264 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Meth:	interface ome.api.IMetadata.loadAnnotations
2013-05-22 10:35:56,265 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:	[class ome.model.containers.Dataset, (3), (), (), PARAMS]
2013-05-22 10:35:56,266 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Meth:	interface ome.api.IContainer.loadContainerHierarchy
2013-05-22 10:35:56,267 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Args:	[class ome.model.containers.Dataset, (3), PARAMS:O[L]]
2013-05-22 10:35:56,267 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:56,268 INFO  [         ome.security.basic.EventHandler] (l.Server-4)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:56,273 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1369215356265] time[8] tag[omero.call.success.ome.logic.MetadataImpl.loadAnnotations]
2013-05-22 10:35:56,273 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:	{3=()}
2013-05-22 10:35:56,278 INFO  [                 org.perf4j.TimingLogger] (l.Server-4) start[1369215356267] time[11] tag[omero.call.success.ome.logic.PojosImpl.loadContainerHierarchy]
2013-05-22 10:35:56,278 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Rslt:	(ome.model.containers.Dataset:Id_3)
2013-05-22 10:35:56,392 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Meth:	interface ome.api.ThumbnailStore.getThumbnailByLongestSideSet
2013-05-22 10:35:56,392 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Args:	[96, (99)]
2013-05-22 10:35:56,400 INFO  [         ome.security.basic.EventHandler] (.Server-13)  Auth:	user=5,group=6,event=3273(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:56,402 INFO  [          omeis.providers.re.HSBStrategy] (.Server-10) taskCount: 8 delta: 128
2013-05-22 10:35:56,403 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215356400] time[2] tag[omero.bulkLoadRenderingSettings]
2013-05-22 10:35:56,403 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215356403] time[0] tag[omero.createAndPrepareMissingRenderingSettings]
2013-05-22 10:35:56,405 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215356403] time[1] tag[omero.bulkLoadMetadata]
2013-05-22 10:35:56,405 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215356403] time[1] tag[omero.loadMetadataByDimensionPool]
2013-05-22 10:35:56,405 INFO  [               ome.services.ThumbnailCtx] (.Server-13) New thumbnail object set size: 0
2013-05-22 10:35:56,405 INFO  [               ome.services.ThumbnailCtx] (.Server-13) Dimension pool size: 0
2013-05-22 10:35:56,405 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215356405] time[0] tag[omero.createMissingThumbnailMetadata]
2013-05-22 10:35:56,422 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215356392] time[30] tag[omero.call.success.ome.services.ThumbnailBean.getThumbnailByLongestSideSet]
2013-05-22 10:35:56,422 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Rslt:	{99=[-1, -40, -1, ... 1 more]}
2013-05-22 10:35:56,487 INFO  [                 org.perf4j.TimingLogger] (.Server-10) start[1369215327961] time[28525] tag[omero.call.success.ome.services.RenderingBean.renderCompressed]
2013-05-22 10:35:56,487 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Rslt:	[-1, -40, -1, ... 1 more]
2013-05-22 10:35:56,487 ERROR [        ome.services.util.ServiceHandler] (.Server-10) Method interface omeis.providers.re.RenderingEngine.renderCompressed invocation took 28525
2013-05-22 10:35:57,715 INFO  [          omeis.providers.re.HSBStrategy] (l.Server-5) taskCount: 8 delta: 112
2013-05-22 10:35:57,791 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1369215327962] time[29828] tag[omero.call.success.ome.services.RenderingBean.renderCompressed]
2013-05-22 10:35:57,791 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	[-1, -40, -1, ... 1 more]
2013-05-22 10:35:57,791 ERROR [        ome.services.util.ServiceHandler] (l.Server-5) Method interface omeis.providers.re.RenderingEngine.renderCompressed invocation took 29828
2013-05-22 10:35:58,074 INFO  [                      omero.cmd.SessionI] (l.Server-1) Added servant to adapter: cbf998d5-1d2b-4f18-a474-3775b68777c5/5077bd29-9676-4fb6-a937-21db77b04052omero.api.RawPixelsStore(omero.api._RawPixelsStoreTie@54873b11)
2013-05-22 10:35:58,077 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Meth:	interface ome.api.RawPixelsStore.setPixelsId
2013-05-22 10:35:58,077 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Args:	[106, true]
2013-05-22 10:35:58,078 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface ome.api.IMetadata.loadAnnotations
2013-05-22 10:35:58,079 INFO  [         ome.security.basic.EventHandler] (.Server-13)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,079 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	[class ome.model.core.Image, (106), (), (), PARAMS]
2013-05-22 10:35:58,081 INFO  [         ome.security.basic.EventHandler] (l.Server-5)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,082 INFO  [             ome.io.nio.FilePathResolver] (.Server-13) Metadata only file, resulting path: /OMERO/ManagedRepository/user-4_5/2013-05/22/10-08-24.236/FURA2-2CH-T.tif
2013-05-22 10:35:58,088 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1369215358079] time[9] tag[omero.call.success.ome.logic.MetadataImpl.loadAnnotations]
2013-05-22 10:35:58,088 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	{106=()}
2013-05-22 10:35:58,095 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Meth:	interface ome.api.IMetadata.loadAnnotations
2013-05-22 10:35:58,095 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Args:	[class ome.model.core.Image, (106), (), (), PARAMS]
2013-05-22 10:35:58,097 INFO  [         ome.security.basic.EventHandler] (l.Server-7)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,102 INFO  [                 org.perf4j.TimingLogger] (l.Server-7) start[1369215358095] time[7] tag[omero.call.success.ome.logic.MetadataImpl.loadAnnotations]
2013-05-22 10:35:58,103 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Rslt:	{106=()}
2013-05-22 10:35:58,124 DEBUG [                   loci.formats.Memoizer] (.Server-13) loaded memo file: /OMERO/BioFormatsCache/OMERO/ManagedRepository/user-4_5/2013-05/22/10-08-24.236/.FURA2-2CH-T.tif.bfmemo (660633 bytes)
2013-05-22 10:35:58,124 DEBUG [                   loci.formats.Memoizer] (.Server-13) start[1369215358084] time[39] tag[loci.formats.Memoizer.loadMemo]
2013-05-22 10:35:58,124 DEBUG [                   loci.formats.Memoizer] (.Server-13) start[1369215358084] time[39] tag[loci.formats.Memoizer.setId]
2013-05-22 10:35:58,124 INFO  [                ome.io.nio.PixelsService] (.Server-13) Creating BfPixelBuffer: /OMERO/ManagedRepository/user-4_5/2013-05/22/10-08-24.236/FURA2-2CH-T.tif Series: 0
2013-05-22 10:35:58,124 WARN  [                ome.io.nio.PixelsService] (.Server-13) ome.io.bioformats.BfPixelBuffer@4f98744a for ome.model.core.Pixels:Id_106
2013-05-22 10:35:58,125 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215358077] time[47] tag[omero.call.success.ome.services.RawPixelsBean.setPixelsId]
2013-05-22 10:35:58,125 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Rslt:	null
2013-05-22 10:35:58,126 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Meth:	interface ome.api.RawPixelsStore.requiresPixelsPyramid
2013-05-22 10:35:58,126 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Args:	()
2013-05-22 10:35:58,128 INFO  [         ome.security.basic.EventHandler] (l.Server-4)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,128 INFO  [                 org.perf4j.TimingLogger] (l.Server-4) start[1369215358127] time[1] tag[omero.call.success.ome.services.RawPixelsBean.requiresPixelsPyramid]
2013-05-22 10:35:58,128 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Rslt:	false
2013-05-22 10:35:58,129 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Meth:	interface ome.api.StatefulServiceInterface.close
2013-05-22 10:35:58,129 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:	()
2013-05-22 10:35:58,135 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:	interface ome.api.IPixels.retrievePixDescription
2013-05-22 10:35:58,135 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:	[106]
2013-05-22 10:35:58,136 INFO  [         ome.security.basic.EventHandler] (l.Server-3)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,137 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:	user=5,group=6,event=3274(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,144 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1369215358135] time[9] tag[omero.call.success.ome.logic.PixelsImpl.retrievePixDescription]
2013-05-22 10:35:58,145 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:	ome.model.core.Pixels:Id_106
2013-05-22 10:35:58,149 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Meth:	interface ome.api.IPixels.retrieveAllRndSettings
2013-05-22 10:35:58,149 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:	[106, 5]
2013-05-22 10:35:58,151 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,157 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1369215358129] time[28] tag[omero.call.success.ome.services.RawPixelsBean.close]
2013-05-22 10:35:58,157 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:	null
2013-05-22 10:35:58,158 INFO  [        ome.services.util.ServiceHandler] (l.Server-1) Cleanup: ome.services.RawPixelsBean@2505bc23
2013-05-22 10:35:58,158 INFO  [                      omero.cmd.SessionI] (l.Server-1) Unregistered servant:cbf998d5-1d2b-4f18-a474-3775b68777c5/5077bd29-9676-4fb6-a937-21db77b04052omero.api.RawPixelsStore(omero.api._RawPixelsStoreTie@54873b11)
2013-05-22 10:35:58,160 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1369215358149] time[11] tag[omero.call.success.ome.logic.PixelsImpl.retrieveAllRndSettings]
2013-05-22 10:35:58,160 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:	(ome.model.display.RenderingDef:Id_106)
2013-05-22 10:35:58,166 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Meth:	interface ome.api.IPixels.retrievePixDescription
2013-05-22 10:35:58,166 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Args:	[106]
2013-05-22 10:35:58,167 INFO  [         ome.security.basic.EventHandler] (.Server-13)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,175 INFO  [                 org.perf4j.TimingLogger] (.Server-13) start[1369215358166] time[8] tag[omero.call.success.ome.logic.PixelsImpl.retrievePixDescription]
2013-05-22 10:35:58,175 INFO  [        ome.services.util.ServiceHandler] (.Server-13)  Rslt:	ome.model.core.Pixels:Id_106
2013-05-22 10:35:58,180 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Meth:	interface ome.api.IPixels.retrieveAllRndSettings
2013-05-22 10:35:58,180 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Args:	[106, 5]
2013-05-22 10:35:58,182 INFO  [         ome.security.basic.EventHandler] (.Server-10)  Auth:	user=5,group=6,event=null(User),sess=cbf998d5-1d2b-4f18-a474-3775b68777c5
2013-05-22 10:35:58,190 INFO  [                 org.perf4j.TimingLogger] (.Server-10) start[1369215358180] time[10] tag[omero.call.success.ome.logic.PixelsImpl.retrieveAllRndSettings]
2013-05-22 10:35:58,190 INFO  [        ome.services.util.ServiceHandler] (.Server-10)  Rslt:	(ome.model.display.RenderingDef:Id_106)
2013-05-22 10:36:00,020 INFO  [ ome.services.blitz.fire.SessionManagerI] (3-thread-2) Performing requestHeartbeats
2013-05-22 10:36:01,681 INFO  [          omeis.providers.re.HSBStrategy] (l.Server-0) taskCount: 8 delta: 128
2013-05-22 10:36:01,771 INFO  [                 org.perf4j.TimingLogger] (l.Server-0) start[1369215327961] time[33809] tag[omero.call.success.ome.services.RenderingBean.renderCompressed]
2013-05-22 10:36:01,771 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Rslt:	[-1, -40, -1, ... 1 more]
2013-05-22 10:36:01,771 ERROR [        ome.services.util.ServiceHandler] (l.Server-0) Method interface omeis.providers.re.RenderingEngine.renderCompressed invocation took 33809
2013-05-22 10:36:15,504 INFO  [          omeis.providers.re.HSBStrategy] (.Server-12) taskCount: 8 delta: 112
2013-05-22 10:36:15,588 INFO  [                 org.perf4j.TimingLogger] (.Server-12) start[1369215327962] time[47625] tag[omero.call.success.ome.services.RenderingBean.renderCompressed]
2013-05-22 10:36:15,588 INFO  [        ome.services.util.ServiceHandler] (.Server-12)  Rslt:	[-1, -40, -1, ... 1 more]
2013-05-22 10:36:15,588 ERROR [        ome.services.util.ServiceHandler] (.Server-12) Method interface omeis.providers.re.RenderingEngine.renderCompressed invocation took 47625

comment:10 Changed 11 years ago by jburel

  • Owner changed from mlinkert to pwalczysko
  • Sprint changed from FS demo 4.x to FS Demo 4.3

Passing it to Petr for testing.

comment:11 Changed 11 years ago by wmoore

JM - what is it you want Petr to test? Melissa hasn't yet done any work to address this issue (as far as I know)?

comment:12 Changed 11 years ago by jburel

to check where we are with recent changes more than anything. i.e. nothing too broken
i.e import thumbnail since it is a "slow" image

Last edited 11 years ago by jburel (previous) (diff)

comment:13 Changed 11 years ago by mlinkert

Pull request opened to address performance issues with VMS and NDPI data: ​https://github.com/openmicroscopy/bioformats/pull/528

That does not affect Trestle data; if Trestle is still a problem please let me know (I assumed that the above logs are from VMS data, but if that's incorrect let me know).

comment:14 Changed 11 years ago by pwalczysko

Checked Trestlu CMU-1 this morning in Insight and Web. Works fine.

comment:15 Changed 11 years ago by pwalczysko

Tried also Windows 7. Also different zoom levels. All done on Gretzky.

comment:16 Changed 11 years ago by pwalczysko

  • Owner pwalczysko deleted

comment:17 Changed 11 years ago by mlinkert

  • Owner set to mlinkert

comment:18 Changed 11 years ago by jburel

  • Sprint changed from FS Demo 4.3 to FS demo 4.4

Moved from sprint FS Demo 4.3

comment:19 Changed 11 years ago by mlinkert

  • Sprint changed from FS demo 4.4 to FS demo 4.x

Pending feedback on ​https://github.com/openmicroscopy/bioformats/pull/528

Pushing to post-Paris.

comment:20 Changed 9 years ago by jburel

  • Resolution set to fixed
  • Status changed from new to closed
  • Version set to 5.1.2

Retested today, no problem noticed.
Closing this ticket.

Note: See TracTickets for help on using tickets. You may also have a look at Agilo extensions to the ticket.

1.3.13-PRO © 2008-2011 Agilo Software all rights reserved (this page was served in: 0.68131 sec.)

© 2000-2014 University of Dundee & Open Microscopy Environment. Creative Commons Attribution 3.0 Unported License
OME source code is available under the GNU General public license or through commercial license from Glencoe Software

We're Hiring!