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

Opened 10 years ago

Closed 9 years ago

Bug: importFileset unknown exception

Reported by: jamoore Owned by: cblackburn
Priority: major Milestone: 5.1.0-m4
Component: General Version: 5.0.5
Keywords: n.a. Cc: fs@…, sbesson
Resources: n.a. Referenced By: n.a.
References: n.a. Remaining Time: n.a.
Sprint: n.a.

Description

Assuming the two logs below are related, it looks as if there can be an odd state in the tests in which a user is removed (or not added??) to a group.

from OMERO-5.0-merge-integration/var/log/master.err:

-! 10/07/14 07:06:45.692 OMERO.Glacier2: warning: dispatch exception: OutgoingAsync.cpp:684: Ice::UnknownException:
   unknown exception:
   ome.conditions.SecurityViolation: User 2101 is not a member of group 2152 and cannot login
        at ome.security.basic.BasicSecuritySystem.loadEventContext(BasicSecuritySystem.java:381)
        at ome.security.basic.EventHandler.doLogin(EventHandler.java:210)
        at ome.security.basic.EventHandler.invoke(EventHandler.java:146)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:241)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
        at com.sun.proxy.$Proxy69.doWork(Unknown Source)
        at ome.services.util.Executor$Impl.execute(Executor.java:457)
        at ome.services.util.Executor$Impl.execute(Executor.java:401)
        at ome.services.blitz.repo.RepositoryDaoImpl.currentContext(RepositoryDaoImpl.java:798)
        at ome.services.blitz.repo.RepositoryDaoImpl.getEventContext(RepositoryDaoImpl.java:792)
        at ome.services.blitz.repo.ManagedRepositoryI.createTemplatePath(ManagedRepositoryI.java:1138)
        at ome.services.blitz.repo.ManagedRepositoryI.internalImport(ManagedRepositoryI.java:251)
        at ome.services.blitz.repo.ManagedRepositoryI.importFileset(ManagedRepositoryI.java:225)
        at omero.grid._ManagedRepositoryTie.importFileset(_ManagedRepositoryTie.java:73)
        at omero.grid._ManagedRepositoryDisp.___importFileset(_ManagedRepositoryDisp.java:364)
        at omero.grid._ManagedRepositoryDisp.__dispatch(_ManagedRepositoryDisp.java:516)
        at IceInternal.Incoming.invoke(Incoming.java:159)
        at Ice.ConnectionI.invokeAll(ConnectionI.java:2357)
        at Ice.ConnectionI.dispatch(ConnectionI.java:1208)
        at Ice.ConnectionI.message(ConnectionI.java:1163)
        at IceInternal.ThreadPool.run(ThreadPool.java:302)
        at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
        at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:643)
        at java.lang.Thread.run(Thread.java:745)

from http://ci.openmicroscopy.org/view/Failing/job/OMERO-5.0-merge-integration-python/339/testReport/test.integration.clitest.test_import/TestImport/testDebugArgument_None_TRACE_/ :

2014-10-07 07:06:45,694 1923       [      main] ERROR        ome.formats.importer.ImportLibrary - Error on import
Ice.UnknownException: null
	at IceInternal.Outgoing.invoke(Outgoing.java:147) ~[ice.jar:na]
	at omero.grid._ManagedRepositoryDelM.importFileset(_ManagedRepositoryDelM.java:44) ~[blitz.jar:na]
	at omero.grid.ManagedRepositoryPrxHelper.importFileset(ManagedRepositoryPrxHelper.java:81) ~[blitz.jar:na]
	at omero.grid.ManagedRepositoryPrxHelper.importFileset(ManagedRepositoryPrxHelper.java:43) ~[blitz.jar:na]
	at ome.formats.importer.ImportLibrary.createImport(ImportLibrary.java:351) ~[blitz.jar:na]
	at ome.formats.importer.ImportLibrary.importImage(ImportLibrary.java:465) ~[blitz.jar:na]
	at ome.formats.importer.ImportLibrary.importCandidates(ImportLibrary.java:271) ~[blitz.jar:na]
	at ome.formats.importer.cli.CommandLineImporter.start(CommandLineImporter.java:216) [blitz.jar:na]
	at ome.formats.importer.cli.CommandLineImporter.main(CommandLineImporter.java:731) [blitz.jar:na]

NB: there are a number of misuses of "omero.group" in the server logs which may be related:

2014-10-07 07:58:39,660 WARN  [    ome.security.basic.BasicEventContext] (l.Server-6) Ignoring invalid requested group: omero.group=de1d6a52-93e0-4d15-ba0d-5c40a6591cc4

These should be group IDs rather than names.

Change History (4)

comment:1 Changed 10 years ago by cblackburn

I've not managed to recreate the problem here but I have found the source of this latter logging. It's an effect of something like:

bin/omero login -s localhost -u user-2 -w ome -g read-only-1
Previously logged in to localhost:4064 as user-2
Created session 721d91df-c276-43aa-88c8-6f08f2790769 (user-2@localhost:4064). Idle timeout: 10.0 min. Current group: read-only-1

giving:

2014-10-07 17:51:13,893 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-5) Created session ServiceFactoryI(session-0f8a564d-7b8e-43a0-b4e2-d077dca6af91/721d91df-c276-43aa-88c8-6f08f2790769) for user user-2 (agent=OMERO.sessions)
2014-10-07 17:51:13,899 INFO  [                      omero.cmd.SessionI] (l.Server-5) Added servant to adapter: 721d91df-c276-43aa-88c8-6f08f2790769/0f8a564d-7b8e-43a0-b4e2-d077dca6af91omero.api.IAdmin(omero.api._IAdminTie@e00bb9a3)
2014-10-07 17:51:13,900 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Meth:	interface ome.api.IAdmin.getEventContext
2014-10-07 17:51:13,900 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:	()
2014-10-07 17:51:13,901 WARN  [    ome.security.basic.BasicEventContext] (l.Server-9) Ignoring invalid requested group: omero.group=read-only-1
2014-10-07 17:51:13,902 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:	user=4,group=8,event=null(User),sess=721d91df-c276-43aa-88c8-6f08f2790769
2014-10-07 17:51:13,903 INFO  [        ome.services.util.ServiceHandler] (3-thread-6)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(721d91df-c276-43aa-88c8-6f08f2790769)
2014-10-07 17:51:13,903 INFO  [        ome.services.util.ServiceHandler] (3-thread-6)  Args:	[null, InternalSF@296723376]
2014-10-07 17:51:13,971 INFO  [         ome.security.basic.EventHandler] (3-thread-6)  Auth:	user=0,group=0,event=null(Sessions),sess=9a2eb637-db6e-4df9-9528-3723243a6848
2014-10-07 17:51:13,981 INFO  [                 org.perf4j.TimingLogger] (3-thread-6) start[1412700673903] time[78] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2014-10-07 17:51:13,981 INFO  [        ome.services.util.ServiceHandler] (3-thread-6)  Rslt:	(ome.model.meta.Experimenter:Id_4, ome.model.meta.ExperimenterGroup:Id_8, (7, 8, 1), ... 4 more)
2014-10-07 17:51:13,982 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1412700673901] time[81] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2014-10-07 17:51:13,982 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:	ome.system.SimpleEventContext@7d571b6b
2014-10-07 17:51:13,985 INFO  [                      omero.cmd.SessionI] (l.Server-9) Added servant to adapter: 721d91df-c276-43aa-88c8-6f08f2790769/0f8a564d-7b8e-43a0-b4e2-d077dca6af91omero.api.ISession(omero.api._ISessionTie@ff2c8d86)
2014-10-07 17:51:13,986 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface ome.api.ISession.getSession
2014-10-07 17:51:13,986 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	[721d91df-c276-43aa-88c8-6f08f2790769]
2014-10-07 17:51:13,986 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1412700673986] time[0] tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
2014-10-07 17:51:13,986 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	ome.model.meta.Session:Id_10
2014-10-07 17:51:14,063 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Meth:	interface ome.api.IAdmin.getEventContext
2014-10-07 17:51:14,063 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:	()
2014-10-07 17:51:14,065 WARN  [    ome.security.basic.BasicEventContext] (l.Server-5) Ignoring invalid requested group: omero.group=read-only-1
2014-10-07 17:51:14,066 INFO  [         ome.security.basic.EventHandler] (l.Server-5)  Auth:	user=4,group=8,event=null(User),sess=721d91df-c276-43aa-88c8-6f08f2790769
2014-10-07 17:51:14,067 INFO  [        ome.services.util.ServiceHandler] (3-thread-6)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.reload(721d91df-c276-43aa-88c8-6f08f2790769)
2014-10-07 17:51:14,067 INFO  [        ome.services.util.ServiceHandler] (3-thread-6)  Args:	[null, InternalSF@296723376]
2014-10-07 17:51:14,069 INFO  [         ome.security.basic.EventHandler] (3-thread-6)  Auth:	user=0,group=0,event=null(Sessions),sess=9a2eb637-db6e-4df9-9528-3723243a6848
2014-10-07 17:51:14,078 INFO  [                 org.perf4j.TimingLogger] (3-thread-6) start[1412700674067] time[11] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2014-10-07 17:51:14,078 INFO  [        ome.services.util.ServiceHandler] (3-thread-6)  Rslt:	(ome.model.meta.Experimenter:Id_4, ome.model.meta.ExperimenterGroup:Id_8, (7, 8, 1), ... 4 more)
2014-10-07 17:51:14,080 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1412700674063] time[16] tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
2014-10-07 17:51:14,080 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:	ome.system.SimpleEventContext@49b5956f
2014-10-07 17:51:14,082 INFO  [o.services.sessions.SessionContext$Count] (l.Server-9) -Reference count: 721d91df-c276-43aa-88c8-6f08f2790769=0
2014-10-07 17:51:14,082 INFO  [                      omero.cmd.SessionI] (l.Server-9) cleanupSelf(ServiceFactoryI(session-0f8a564d-7b8e-43a0-b4e2-d077dca6af91/721d91df-c276-43aa-88c8-6f08f2790769)).
2014-10-07 17:51:14,082 INFO  [                      omero.cmd.SessionI] (l.Server-9) Unregistered servant:721d91df-c276-43aa-88c8-6f08f2790769/0f8a564d-7b8e-43a0-b4e2-d077dca6af91omero.api.IAdmin(omero.api._IAdminTie@e00bb9a3)
2014-10-07 17:51:14,082 INFO  [                      omero.cmd.SessionI] (l.Server-9) Removed servant from adapter: 0f8a564d-7b8e-43a0-b4e2-d077dca6af91omero.api.IAdmin
2014-10-07 17:51:14,082 INFO  [                      omero.cmd.SessionI] (l.Server-9) Unregistered servant:721d91df-c276-43aa-88c8-6f08f2790769/0f8a564d-7b8e-43a0-b4e2-d077dca6af91omero.api.ISession(omero.api._ISessionTie@ff2c8d86)
2014-10-07 17:51:14,082 INFO  [                      omero.cmd.SessionI] (l.Server-9) Removed servant from adapter: 0f8a564d-7b8e-43a0-b4e2-d077dca6af91omero.api.ISession}}}
So the correct group is logged into okay but this warning arises

comment:2 Changed 10 years ago by jamoore

  • Milestone changed from 5.1.0-m2 to 5.1.0-m3

comment:4 Changed 9 years ago by jamoore

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

Agreed.

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.65678 sec.)

We're Hiring!