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
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:9 Changed 9 years ago by dlindner
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:13 Changed 9 years ago by dlindner
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:15 Changed 9 years ago by dlindner
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
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?