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 #12120 (closed)

Opened 10 years ago

Closed 9 years ago

BUG: QA7984 - Abnormal termination on ONEE

Reported by: omero-qa Owned by: dlindner
Priority: critical Milestone: 5.1.1
Component: Insight Version: 5.0.0
Keywords: n.a. Cc: DSudar@…, N.Hegarat@…, java@…
Resources: n.a. Referenced By: n.a.
References: n.a. Remaining Time: n.a.
Sprint: n.a.

Description

https://www.openmicroscopy.org/qa2/qa2/qa/feedback/7984/

Comment: Clicked on the "Original metadata" listing the in the Acquisition tab while in Insight 5.0.0 connected with server 5.0.0.

java.lang.Exception: Abnormal termination due to an uncaught exception.

Ice.ObjectNotExistException

    id.name = "IHandlebaec58bc-5310-45b0-a8be-e329ff1afd79"

    id.category = "7cc48c45-5482-465d-8d62-e54557ce835a"

    facet = ""

    operation = "getStatus"

	at IceInternal.Outgoing.invoke(Outgoing.java:158)

	at omero.cmd._HandleDelM.getStatus(_HandleDelM.java:242)

	at omero.cmd.HandlePrxHelper.getStatus(HandlePrxHelper.java:1099)

	at omero.cmd.HandlePrxHelper.getStatus(HandlePrxHelper.java:1064)

	at omero.cmd.CmdCallbackI.poll(CmdCallbackI.java:282)

	at omero.cmd.CmdCallbackI$1.run(CmdCallbackI.java:125)

Abnormal termination due to an uncaught exception.

Ice.ObjectNotExistException

    id.name = "IHandlebaec58bc-5310-45b0-a8be-e329ff1afd79"

    id.category = "7cc48c45-5482-465d-8d62-e54557ce835a"

    facet = ""

    operation = "getStatus"

	at IceInternal.Outgoing.invoke(Outgoing.java:158)

	at omero.cmd._HandleDelM.getStatus(_HandleDelM.java:242)

	at omero.cmd.HandlePrxHelper.getStatus(HandlePrxHelper.java:1099)

	at omero.cmd.HandlePrxHelper.getStatus(HandlePrxHelper.java:1064)

	at omero.cmd.CmdCallbackI.poll(CmdCallbackI.java:282)

	at omero.cmd.CmdCallbackI$1.run(CmdCallbackI.java:125)

Exception in thread "Thread-122"



	at org.openmicroscopy.shoola.env.ui.UserNotifierImpl.showErrorDialog(UserNotifierImpl.java:191)

	at org.openmicroscopy.shoola.env.ui.UserNotifierImpl.notifyError(UserNotifierImpl.java:291)

	at org.openmicroscopy.shoola.env.AbnormalExitHandler.doTermination(AbnormalExitHandler.java:147)

	at org.openmicroscopy.shoola.env.AbnormalExitHandler.terminate(AbnormalExitHandler.java:85)

	at org.openmicroscopy.shoola.env.RootThreadGroup.uncaughtException(RootThreadGroup.java:69)

	at java.lang.Thread.dispatchUncaughtException(Unknown Source)

Change History (17)

comment:1 Changed 10 years ago by jamoore

  • Cc java@… added
  • Component changed from from QA to Insight
  • Milestone changed from Unscheduled to 5.0.2
  • Owner jmoore deleted
  • Priority changed from minor to critical
  • Summary changed from BUG:Feedback 7984 to BUG: QA7984 - Abnormal termination on ONEE

A ObjectNotExistException means that either 1) the session has been completely closed or that 2) just the single instance has been closed/removed from the session. I don't think that the IHandle.getStatus failure is related to the OriginalMetadata load itself, but perhaps some kind of refresh was triggered that was holding onto an old omero.cmd.Callback item?

comment:2 Changed 10 years ago by bpindelski

I've opened this ticket for investigation mainly. I don't know if there is a code bug to fix or was it just a manifestation of a network error. Josh/J-M: If anything indicates that it was the latter, I think this ticket can be closed...

comment:3 Changed 10 years ago by jamoore

  • Owner set to jburel

Passing off to J-m for a ticket decision. My description above is all I know.

comment:4 Changed 10 years ago by jburel

  • Resolution set to invalid
  • Status changed from new to closed

That could have been happening after a switch user or closing services when a group was removed. The issues noticed while doing such action have been fixed.
Closing is ticket as invalid. We can re-open if we notice a similar issue

comment:5 Changed 10 years ago by dlindner

  • Cc N.Hegarat@… added
  • Resolution invalid deleted
  • Status changed from closed to reopened

Reopened ticket, similar issue recently reported here:
https://www.openmicroscopy.org/qa2/qa/feedback/9520/
Though still not really clear, how to reproduce the bug.

comment:6 Changed 9 years ago by dlindner

Another one with Insight 5.0.4 on Nightshade server (5.0.5): https://www.openmicroscopy.org/qa2/qa/feedback/9573/

comment:7 Changed 9 years ago by dlindner

Here's the relevant part from Blitz.log wrt QA 9573

2014-10-03 15:08:16,495 INFO  [                      omero.cmd.SessionI] (l.Server-5) Added servant to adapter: 801d5e27-f15d-4a5b-949e-e12f4df53f4b/IHandle0afa4de1-b14f-4485-811e-3fbcf0bb00ec(omero.cmd._HandleTie@4eac9722)
2014-10-03 15:08:16,500 INFO  [        ome.services.util.ServiceHandler] (2-thread-5)  Executor.doWork -- omero.cmd.HandleI.run(801d5e27-f15d-4a5b-949e-e12f4df53f4b/IHandle0afa4de1-b14f-4485-811e-3fbcf0bb00ec, omero.cmd.basic.DoAllI@68712f17)
2014-10-03 15:08:16,500 INFO  [        ome.services.util.ServiceHandler] (2-thread-5)  Args:	[null, InternalSF@1639462715]
2014-10-03 15:08:16,508 INFO  [         ome.security.basic.EventHandler] (2-thread-5)  Auth:	user=4606,group=1202,event=40606924(User),sess=801d5e27-f15d-4a5b-949e-e12f4df53f4b
2014-10-03 15:08:16,509 INFO  [    ome.security.basic.BasicEventContext] (2-thread-5)  cctx:	group=-1
2014-10-03 15:08:16,515 INFO  [         ome.security.basic.EventHandler] (2-thread-5)  Auth:	user=4606,group=-1,event=40606925(User),sess=801d5e27-f15d-4a5b-949e-e12f4df53f4b
2014-10-03 15:08:16,516 INFO  [    ome.security.basic.BasicEventContext] (2-thread-5)  cctx:	group=-1
2014-10-03 15:08:16,521 INFO  [         ome.security.basic.EventHandler] (2-thread-5)  Auth:	user=4606,group=-1,event=40606926(User),sess=801d5e27-f15d-4a5b-949e-e12f4df53f4b
2014-10-03 15:08:16,521 INFO  [    ome.security.basic.BasicEventContext] (2-thread-5)  cctx:	group=-1
2014-10-03 15:08:16,525 INFO  [         ome.security.basic.EventHandler] (2-thread-5)  Auth:	user=4606,group=-1,event=40606927(User),sess=801d5e27-f15d-4a5b-949e-e12f4df53f4b
2014-10-03 15:08:16,529 INFO  [             ome.io.nio.FilePathResolver] (2-thread-5) Metadata only file, resulting path: /opt/omero-data/OMERO/ManagedRepository/mdaazcosta_4606/2014-10/03/12-01-51.750/GFP_ChiC_pBADChiR2_140912_06_6_R3D.dv
2014-10-03 15:08:16,560 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Meth:	interface ome.api.IQuery.findAllByQuery
2014-10-03 15:08:16,560 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:	[select fs from Fileset as fs join fetch fs.images as image left outer join fetch fs.usedFiles as usedFile join fetch usedFile.originalFile as f join fetch f.hasher where image.id in (:imageIds), PARAMS:imageIds=ArrayList(1) ]
2014-10-03 15:08:16,561 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:	user=4606,group=1202,event=null(User),sess=801d5e27-f15d-4a5b-949e-e12f4df53f4b
2014-10-03 15:08:16,567 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1412345296560] time[6] tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2014-10-03 15:08:16,567 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:	(ome.model.fs.Fileset:Id_26713)
2014-10-03 15:08:16,578 DEBUG [                   loci.formats.Memoizer] (2-thread-5) loaded memo file: /opt/omero-data/OMERO/BioFormatsCache/opt/omero-data/OMERO/ManagedRepository/mdaazcosta_4606/2014-10/03/12-01-51.750/.GFP_ChiC_pBADChiR2_140912_06_6_R3D.dv.bfmemo (1105647 bytes)
2014-10-03 15:08:16,578 DEBUG [                   loci.formats.Memoizer] (2-thread-5) start[1412345296530] time[47] tag[loci.formats.Memoizer.loadMemo]
2014-10-03 15:08:16,578 DEBUG [                   loci.formats.Memoizer] (2-thread-5) start[1412345296530] time[47] tag[loci.formats.Memoizer.setId]
2014-10-03 15:08:16,579 INFO  [                 org.perf4j.TimingLogger] (2-thread-5) start[1412345296521] time[57] tag[omero.request.step.0]
2014-10-03 15:08:16,579 INFO  [    ome.security.basic.BasicEventContext] (2-thread-5)  cctx:	group=-1
2014-10-03 15:08:16,585 INFO  [        omero.cmd.basic.DoAllI.@68712f17] (.Server-13) Add callback: ]a|JvFm$^>TC\/w-.uhY(/4d6201fe-8619-4806-a131-3ee68ce9bf4a
2014-10-03 15:08:16,586 INFO  [         ome.security.basic.EventHandler] (2-thread-5)  Auth:	user=4606,group=-1,event=40606928(User),sess=801d5e27-f15d-4a5b-949e-e12f4df53f4b
2014-10-03 15:08:16,586 INFO  [                 org.perf4j.TimingLogger] (2-thread-5) start[1412345296508] time[77] tag[omero.request]
2014-10-03 15:08:16,586 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Meth:	interface ome.api.IMetadata.loadSpecifiedAnnotationsLinkedTo
2014-10-03 15:08:16,586 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Args:	[class ome.model.annotations.CommentAnnotation, (openmicroscopy.org/omero/import/fileTransfer), (openmicroscopy.org/omero/import/logFile), class ome.model.fs.Fileset, (26713), PARAMS]
2014-10-03 15:08:16,587 INFO  [         ome.security.basic.EventHandler] (l.Server-0)  Auth:	user=4606,group=1202,event=null(User),sess=801d5e27-f15d-4a5b-949e-e12f4df53f4b
2014-10-03 15:08:16,588 INFO  [                 org.perf4j.TimingLogger] (2-thread-5) start[1412345296500] time[87] tag[omero.call.success.omero.cmd.HandleI$1.doWork]
2014-10-03 15:08:16,588 INFO  [        ome.services.util.ServiceHandler] (2-thread-5)  Rslt:	(null)
2014-10-03 15:08:16,588 WARN  [              ome.services.util.Executor] (2-thread-5) Logins left: 1
2014-10-03 15:08:16,588 INFO  [                 org.perf4j.TimingLogger] (2-thread-5) start[1412345296500] time[88] tag[omero.request.tx]
2014-10-03 15:08:16,588 INFO  [        omero.cmd.basic.DoAllI.@68712f17] (2-thread-5) notify finished: omero.cmd.DoAllRsp@4debc561/omero.cmd.Status@31eb8b3e
2014-10-03 15:08:16,594 INFO  [                 org.perf4j.TimingLogger] (l.Server-0) start[1412345296586] time[8] tag[omero.call.success.ome.logic.MetadataImpl.loadSpecifiedAnnotationsLinkedTo]
2014-10-03 15:08:16,594 INFO  [        ome.services.util.ServiceHandler] (l.Server-0)  Rslt:	{}
2014-10-03 15:08:16,599 INFO  [        omero.cmd.basic.DoAllI.@68712f17] (l.Server-4) getResponse: omero.cmd.DoAllRsp@4debc561
2014-10-03 15:08:16,647 INFO  [                      omero.cmd.SessionI] (.Server-10) Added servant to adapter: 801d5e27-f15d-4a5b-949e-e12f4df53f4b/313b2144-2751-4ea9-9d65-90106c10c519omero.api.RawPixelsStore(omero.api._RawPixelsStoreTie@5b496970)
2014-10-03 15:08:16,750 INFO  [                      omero.cmd.SessionI] (.Server-14) Unregistered servant:801d5e27-f15d-4a5b-949e-e12f4df53f4b/IHandle0afa4de1-b14f-4485-811e-3fbcf0bb00ec(omero.cmd._HandleTie@4eac9722)
2014-10-03 15:08:16,750 INFO  [        omero.cmd.basic.DoAllI.@68712f17] (.Server-14) Closing...

comment:8 Changed 9 years ago by jamoore

  • Milestone changed from 5.0.2 to 5.1.0-m3

comment:10 Changed 9 years ago by dlindner

Bug triggered while importer still running, see https://www.openmicroscopy.org/qa2/qa/feedback/10381/

comment:11 Changed 9 years ago by dlindner

Persists in 5.1.0: https://www.openmicroscopy.org/qa2/qa/feedback/10909/
But I still don't have a clue about how to trigger this bug.

comment:12 Changed 9 years ago by jburel

  • Milestone changed from 5.1.0 to 5.1.1

Still struggling to trigger the issue. Pushing to 5.1.1.

comment:14 Changed 9 years ago by jamoore

Dom, if in another thread you actively close the Handle then you will get a Ice.ObjectNoExistException. In general, whatever top-level handler we have should simply catch these exceptions. They are not critical and are more of the form, "service already closed".

comment:16 Changed 9 years ago by jburel

  • Owner changed from jburel to dlindner
  • Status changed from reopened to accepted

comment:17 Changed 9 years ago by dlindner

  • Resolution set to fixed
  • Status changed from accepted to closed
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.67119 sec.)

We're Hiring!