[ome-users] Upgraded but LDAP users can't pull their groups

Josh Moore josh at glencoesoftware.com
Tue Aug 16 07:46:13 BST 2011


Hi Leon,

On Aug 16, 2011, at 1:57 AM, Leon Kolchinsky wrote:

> Here is an update,
> 
> Users in External Users OU don't actually have any groups ;(
> Might this be the reason that they can't login?

Definitely. If the new_user_group query returns nothing, then the user would be in no group and therefore couldn't login.

> And by the way, is there any option to make Omero re-read groups for users
> on every login?

Not yet, no. The story https://trac.openmicroscopy.org.uk/ome/ticket/1382 links to several improvements we would like to make to the LDAP authentication plugin, one of these is a synchronization option.

> As you know some of those users added to new groups but those new groups are
> not updated in the OMERO ;(
> Or may be syncronize groups for LDAP users once a day?

That's definitely also part of #1382, but not something we can implement immediately.

> BTW, I couldn't find a way to delete user via WebAdmin. Where do you hide
> this option?

In general, deleting a user is difficult since something must happen to their data, therefore it's not an option in the UI. Instead disable the user by unselecting "Active" from WebAdmin.

> Cheers,
> Leon Kolchinsky

Cheers,
~Josh

> 
> 
> On Tue, Aug 16, 2011 at 09:42, Leon Kolchinsky <lkolchin at gmail.com> wrote:
> 
>> Thanks Josh,
>> 
>> The thing is that our users residing in several OU's (Staff, Students,
>> ExternbalUsers...)
>> I've checked again and users from Staff OU can login without an issue but
>> users from another OU=External Users can't (I just couldn't check it
>> yeaterday with someone else's username from Staff OU, and only had details
>> of users in External Users OU).
>> 
>> So looking at Blitz-0.log, here is how the successfull login looks like:
>> 
>> 2011-08-16 09:27:41,810 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest)
>> 2011-08-16 09:27:41,810 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:41,811 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-7)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:41,814 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-7) start[1313450861810] time[4]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
>> 2011-08-16 09:27:41,814 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Rslt:    true
>> 2011-08-16 09:27:41,817 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.createSession
>> 2011-08-16 09:27:41,817 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:41,844 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-8)  Auth:
>> user=0,group=0,event=60614(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:41,856 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-8) Adding log:INSERT,class ome.model.meta.Session,6675
>> 2011-08-16 09:27:41,872 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-8) start[1313450861817] time[55]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
>> 2011-08-16 09:27:41,872 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Rslt:    (ome.model.meta.Experimenter:Id_1,
>> ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
>> 2011-08-16 09:27:41,876 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-8) Created session
>> ServiceFactoryI(session-cf2d35f8-f66d-4abf-bdd9-02d97c692848/2efd291e-1437-4f21-9f62-4d6560710ed6)
>> for user guest (agent=OMERO.web)
>> 2011-08-16 09:27:41,881 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-5) Added servant to adapter:
>> 2efd291e-1437-4f21-9f62-4d6560710ed6/cf2d35f8-f66d-4abf-bdd9-02d97c692848omero.api.ISession(omero.api._ISessionTie at 143bed82
>> )
>> 2011-08-16 09:27:41,883 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Meth:    interface ome.api.ISession.getSession
>> 2011-08-16 09:27:41,883 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Args:    [2efd291e-1437-4f21-9f62-4d6560710ed6]
>> 2011-08-16 09:27:41,883 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-5) start[1313450861883] time[0]
>> tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
>> 2011-08-16 09:27:41,883 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Rslt:    ome.model.meta.Session:Id_6675
>> 2011-08-16 09:27:41,893 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-5) Added servant to adapter:
>> 2efd291e-1437-4f21-9f62-4d6560710ed6/cf2d35f8-f66d-4abf-bdd9-02d97c692848omero.api.IAdmin(omero.api._IAdminTie at 48ae81dd
>> )
>> 2011-08-16 09:27:41,894 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Meth:    interface ome.api.IAdmin.getEventContext
>> 2011-08-16 09:27:41,894 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Args:    ()
>> 2011-08-16 09:27:41,896 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-5)  Auth:
>> user=1,group=2,event=null(User),sess=2efd291e-1437-4f21-9f62-4d6560710ed6
>> 2011-08-16 09:27:41,897 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.reload(2efd291e-1437-4f21-9f62-4d6560710ed6)
>> 2011-08-16 09:27:41,897 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:41,903 INFO  [         ome.security.basic.EventHandler]
>> (-thread-24)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:41,913 INFO  [                 org.perf4j.TimingLogger]
>> (-thread-24) start[1313450861897] time[16]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
>> 2011-08-16 09:27:41,913 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Rslt:    (ome.model.meta.Experimenter:Id_1,
>> ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
>> 2011-08-16 09:27:41,914 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-5) start[1313450861894] time[20]
>> tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
>> 2011-08-16 09:27:41,914 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Rslt:    ome.system.SimpleEventContext at 5afa24c9
>> 2011-08-16 09:27:41,918 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-2) Added servant to adapter:
>> 2efd291e-1437-4f21-9f62-4d6560710ed6/cf2d35f8-f66d-4abf-bdd9-02d97c692848omero.api.IConfig(omero.api._IConfigTie at 224396cd
>> )
>> 2011-08-16 09:27:41,920 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Meth:    interface ome.api.IConfig.getConfigValue
>> 2011-08-16 09:27:41,920 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Args:    [omero.version]
>> 2011-08-16 09:27:41,935 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-2)  Auth:
>> user=1,group=2,event=60615(User),sess=2efd291e-1437-4f21-9f62-4d6560710ed6
>> 2011-08-16 09:27:41,939 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-2) start[1313450861920] time[19]
>> tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
>> 2011-08-16 09:27:41,939 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Rslt:    4.3.1-6d4b8c61-b2602
>> 2011-08-16 09:27:41,957 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(ajft)
>> 2011-08-16 09:27:41,957 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:41,958 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-7)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:41,959 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-7) start[1313450861957] time[2]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
>> 2011-08-16 09:27:41,959 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Rslt:    null
>> 2011-08-16 09:27:41,959 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.executeCheckPasswordRW(ajft)
>> 2011-08-16 09:27:41,959 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:41,972 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-7)  Auth:
>> user=0,group=0,event=60616(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:42,496 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,203
>> 2011-08-16 09:27:42,498 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,204
>> 2011-08-16 09:27:42,500 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,205
>> 2011-08-16 09:27:42,501 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,206
>> 2011-08-16 09:27:42,503 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,207
>> 2011-08-16 09:27:42,505 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,208
>> 2011-08-16 09:27:42,506 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,209
>> 2011-08-16 09:27:42,508 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,210
>> 2011-08-16 09:27:42,510 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,211
>> 2011-08-16 09:27:42,511 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,212
>> 2011-08-16 09:27:42,513 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,213
>> 2011-08-16 09:27:42,515 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,214
>> 2011-08-16 09:27:42,517 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,215
>> 2011-08-16 09:27:42,519 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,216
>> 2011-08-16 09:27:42,520 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,217
>> 2011-08-16 09:27:42,523 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,218
>> 2011-08-16 09:27:42,549 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.ExperimenterGroup,219
>> 2011-08-16 09:27:42,573 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.Experimenter,652
>> 2011-08-16 09:27:42,632 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,653
>> 2011-08-16 09:27:42,637 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,654
>> 2011-08-16 09:27:42,639 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,655
>> 2011-08-16 09:27:42,642 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,656
>> 2011-08-16 09:27:42,644 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,657
>> 2011-08-16 09:27:42,648 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,658
>> 2011-08-16 09:27:42,651 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,659
>> 2011-08-16 09:27:42,654 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,660
>> 2011-08-16 09:27:42,657 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,661
>> 2011-08-16 09:27:42,660 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,662
>> 2011-08-16 09:27:42,663 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,663
>> 2011-08-16 09:27:42,666 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,664
>> 2011-08-16 09:27:42,670 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,665
>> 2011-08-16 09:27:42,674 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,666
>> 2011-08-16 09:27:42,677 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,667
>> 2011-08-16 09:27:42,681 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,668
>> 2011-08-16 09:27:42,685 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,669
>> 2011-08-16 09:27:42,690 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-7) Adding log:INSERT,class ome.model.meta.GroupExperimenterMap,670
>> 2011-08-16 09:27:42,742 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-7) start[1313450861959] time[783]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$9.doWork]
>> 2011-08-16 09:27:42,742 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Rslt:    true
>> 2011-08-16 09:27:42,743 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.createSession
>> 2011-08-16 09:27:42,743 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:42,809 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-8)  Auth:
>> user=0,group=0,event=60617(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:42,842 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-8) Adding log:INSERT,class ome.model.meta.Session,6676
>> 2011-08-16 09:27:42,856 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-8) start[1313450862744] time[112]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
>> 2011-08-16 09:27:42,856 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Rslt:    (ome.model.meta.Experimenter:Id_652,
>> ome.model.meta.ExperimenterGroup:Id_203, [203, 205, 204, 207, 206, 216, 217,
>> 218, 219, 212, 213, 214, 215, 208, 209, 210, 211, 1], ... 4 more)
>> 2011-08-16 09:27:42,858 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-8) Created session
>> ServiceFactoryI(session-a4a0353e-b8bd-42f9-a9e5-464376669558/ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> for user ajft (agent=OMERO.web)
>> 2011-08-16 09:27:42,863 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-1) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/a4a0353e-b8bd-42f9-a9e5-464376669558omero.api.ISession(omero.api._ISessionTie at 7e970be7
>> )
>> 2011-08-16 09:27:42,864 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Meth:    interface ome.api.ISession.getSession
>> 2011-08-16 09:27:42,864 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Args:    [ff2e7caf-f1dd-4df4-b6f3-7cc014619816]
>> 2011-08-16 09:27:42,864 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-4) start[1313450862864] time[0]
>> tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
>> 2011-08-16 09:27:42,864 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Rslt:    ome.model.meta.Session:Id_6676
>> 2011-08-16 09:27:42,871 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-4) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/a4a0353e-b8bd-42f9-a9e5-464376669558omero.api.IConfig(omero.api._IConfigTie at 698adc62
>> )
>> 2011-08-16 09:27:42,872 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Meth:    interface ome.api.IConfig.getConfigValue
>> 2011-08-16 09:27:42,872 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Args:    [omero.router.insecure]
>> 2011-08-16 09:27:42,900 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-4)  Auth:
>> user=652,group=203,event=60618(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:42,904 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-4) start[1313450862872] time[32]
>> tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
>> 2011-08-16 09:27:42,904 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Rslt:    OMERO.Glacier2/router:tcp -p 4063 -h @omero.host@
>> 2011-08-16 09:27:42,912 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-2) Found session locally: ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:42,914 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-2) Rejoining session
>> ServiceFactoryI(session-c4e52d45-b19b-4934-b006-140ef665e972/ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> (agent=OMERO.web;secure=False)
>> 2011-08-16 09:27:42,916 INFO  [e.services.sessions.SessionContext$Count]
>> (l.Server-7) -Reference count: ff2e7caf-f1dd-4df4-b6f3-7cc014619816=1
>> 2011-08-16 09:27:42,919 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-7) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/c4e52d45-b19b-4934-b006-140ef665e972omero.api.IConfig(omero.api._IConfigTie at 5017a6a0
>> )
>> 2011-08-16 09:27:42,921 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Meth:    interface ome.api.IConfig.getConfigValue
>> 2011-08-16 09:27:42,921 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Args:    [omero.router.insecure]
>> 2011-08-16 09:27:42,934 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-7)  Auth:
>> user=652,group=203,event=60619(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:42,938 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-7) start[1313450862921] time[17]
>> tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
>> 2011-08-16 09:27:42,938 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Rslt:    OMERO.Glacier2/router:tcp -p 4063 -h @omero.host@
>> 2011-08-16 09:27:42,945 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-3) Found session locally: ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:42,947 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-3) Rejoining session
>> ServiceFactoryI(session-3d5e88a4-7e2e-420d-b5b5-27d9cd174a09/ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> (agent=OMERO.web;secure=False;secure=False)
>> 2011-08-16 09:27:42,949 INFO  [e.services.sessions.SessionContext$Count]
>> (l.Server-0) -Reference count: ff2e7caf-f1dd-4df4-b6f3-7cc014619816=1
>> 2011-08-16 09:27:42,953 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-0) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/3d5e88a4-7e2e-420d-b5b5-27d9cd174a09omero.api.IAdmin(omero.api._IAdminTie at 6e6970b6
>> )
>> 2011-08-16 09:27:42,954 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Meth:    interface ome.api.IAdmin.getEventContext
>> 2011-08-16 09:27:42,954 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Args:    ()
>> 2011-08-16 09:27:42,955 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-0)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:42,956 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.reload(ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> 2011-08-16 09:27:42,956 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:42,956 INFO  [         ome.security.basic.EventHandler]
>> (-thread-24)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:42,963 INFO  [                 org.perf4j.TimingLogger]
>> (-thread-24) start[1313450862956] time[7]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
>> 2011-08-16 09:27:42,963 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Rslt:    (ome.model.meta.Experimenter:Id_652,
>> ome.model.meta.ExperimenterGroup:Id_203, [203, 205, 204, 207, 206, 216, 217,
>> 218, 219, 212, 213, 214, 215, 208, 209, 210, 211, 1], ... 4 more)
>> 2011-08-16 09:27:42,964 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-0) start[1313450862954] time[10]
>> tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
>> 2011-08-16 09:27:42,964 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Rslt:    ome.system.SimpleEventContext at 7546459c
>> 2011-08-16 09:27:42,967 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Meth:    interface ome.api.IAdmin.getExperimenter
>> 2011-08-16 09:27:42,967 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Args:    [652]
>> 2011-08-16 09:27:42,968 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-8)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:42,973 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-8) start[1313450862967] time[6]
>> tag[omero.call.success.ome.logic.AdminImpl.getExperimenter]
>> 2011-08-16 09:27:42,974 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Rslt:    ome.model.meta.Experimenter:Id_652
>> 2011-08-16 09:27:42,980 INFO  [e.services.sessions.SessionContext$Count]
>> (l.Server-1) -Reference count: 2efd291e-1437-4f21-9f62-4d6560710ed6=0
>> 2011-08-16 09:27:42,992 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Meth:    interface ome.api.IAdmin.getEventContext
>> 2011-08-16 09:27:42,992 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Args:    ()
>> 2011-08-16 09:27:42,993 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-1)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:42,993 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.reload(ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> 2011-08-16 09:27:42,993 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:42,994 INFO  [         ome.security.basic.EventHandler]
>> (-thread-24)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:43,000 INFO  [                 org.perf4j.TimingLogger]
>> (-thread-24) start[1313450862993] time[7]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
>> 2011-08-16 09:27:43,001 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Rslt:    (ome.model.meta.Experimenter:Id_652,
>> ome.model.meta.ExperimenterGroup:Id_203, [203, 205, 204, 207, 206, 216, 217,
>> 218, 219, 212, 213, 214, 215, 208, 209, 210, 211, 1], ... 4 more)
>> 2011-08-16 09:27:43,001 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-1) start[1313450862992] time[9]
>> tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
>> 2011-08-16 09:27:43,001 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Rslt:    ome.system.SimpleEventContext at 7f24d879
>> 2011-08-16 09:27:43,003 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Meth:    interface ome.api.IAdmin.getExperimenter
>> 2011-08-16 09:27:43,003 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Args:    [652]
>> 2011-08-16 09:27:43,004 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-3)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:43,009 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-3) start[1313450863003] time[6]
>> tag[omero.call.success.ome.logic.AdminImpl.getExperimenter]
>> 2011-08-16 09:27:43,010 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Rslt:    ome.model.meta.Experimenter:Id_652
>> 2011-08-16 09:27:43,022 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Meth:    interface ome.api.IAdmin.lookupGroup
>> 2011-08-16 09:27:43,022 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Args:    [user]
>> 2011-08-16 09:27:43,023 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-4)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:43,031 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-4) start[1313450863022] time[9]
>> tag[omero.call.success.ome.logic.AdminImpl.lookupGroup]
>> 2011-08-16 09:27:43,031 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Rslt:    ome.model.meta.ExperimenterGroup:Id_1
>> 2011-08-16 09:27:45,950 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-6) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/3d5e88a4-7e2e-420d-b5b5-27d9cd174a09omero.api.IConfig(omero.api._IConfigTie at 676f64cc
>> )
>> 2011-08-16 09:27:45,952 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Meth:    interface ome.api.IConfig.getConfigValue
>> 2011-08-16 09:27:45,952 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Args:    [omero.version]
>> 2011-08-16 09:27:45,967 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-6)  Auth:
>> user=652,group=203,event=60620(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:45,970 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-6) start[1313450865953] time[17]
>> tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
>> 2011-08-16 09:27:45,970 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Rslt:    4.3.1-6d4b8c61-b2602
>> 2011-08-16 09:27:45,987 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-9) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/3d5e88a4-7e2e-420d-b5b5-27d9cd174a09omero.api.IQuery(omero.api._IQueryTie at 36495ac8
>> )
>> 2011-08-16 09:27:45,990 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-9)  Meth:    interface ome.api.IQuery.findAllByQuery
>> 2011-08-16 09:27:45,990 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-9)  Args:    [select distinct obj from ExperimenterGroup as obj
>> left outer join fetch obj.groupExperimenterMap as map left outer join fetch
>> map.child e where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
>> 2011-08-16 09:27:45,991 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-9)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:45,996 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-9) start[1313450865990] time[6]
>> tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
>> 2011-08-16 09:27:45,996 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-9)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_203)
>> 2011-08-16 09:27:46,002 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Meth:    interface ome.api.IQuery.findAllByQuery
>> 2011-08-16 09:27:46,003 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Args:    [select e from ExperimenterGroup as e where e.id in
>> (:ids), PARAMS:ids=ArrayList(17) ]
>> 2011-08-16 09:27:46,003 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-5)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,012 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-5) start[1313450866003] time[9]
>> tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
>> 2011-08-16 09:27:46,013 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_203,
>> ome.model.meta.ExperimenterGroup:Id_204,
>> ome.model.meta.ExperimenterGroup:Id_205, ... 14 more)
>> 2011-08-16 09:27:46,427 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Meth:    interface ome.api.IQuery.findAllByQuery
>> 2011-08-16 09:27:46,427 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Args:    [select distinct obj from ExperimenterGroup as obj
>> left outer join fetch obj.groupExperimenterMap as map left outer join fetch
>> map.child e where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
>> 2011-08-16 09:27:46,429 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-1)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,439 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-1) start[1313450866427] time[12]
>> tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
>> 2011-08-16 09:27:46,440 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_203)
>> 2011-08-16 09:27:46,444 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Meth:    interface ome.api.IQuery.findAllByQuery
>> 2011-08-16 09:27:46,445 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Args:    [select e from ExperimenterGroup as e where e.id in
>> (:ids), PARAMS:ids=ArrayList(17) ]
>> 2011-08-16 09:27:46,446 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-4)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,450 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-4) start[1313450866445] time[5]
>> tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
>> 2011-08-16 09:27:46,451 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_203,
>> ome.model.meta.ExperimenterGroup:Id_204,
>> ome.model.meta.ExperimenterGroup:Id_205, ... 14 more)
>> 2011-08-16 09:27:46,462 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-2) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/3d5e88a4-7e2e-420d-b5b5-27d9cd174a09omero.api.ITimeline(omero.api._ITimelineTie at 214e13e
>> )
>> 2011-08-16 09:27:46,464 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Executor.doWork --
>> ome.services.blitz.impl.TimelineI.getMostRecentObjects
>> 2011-08-16 09:27:46,464 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:46,466 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-2)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,487 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-6) Found session locally: ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,489 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-6) Rejoining session
>> ServiceFactoryI(session-290df595-e731-4ccf-b171-4536a9f34c42/ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> (agent=OMERO.web)
>> 2011-08-16 09:27:46,493 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-7) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/290df595-e731-4ccf-b171-4536a9f34c42omero.api.ISession(omero.api._ISessionTie at 1fc643f5
>> )
>> 2011-08-16 09:27:46,495 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Meth:    interface ome.api.ISession.getSession
>> 2011-08-16 09:27:46,495 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Args:    [ff2e7caf-f1dd-4df4-b6f3-7cc014619816]
>> 2011-08-16 09:27:46,495 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-7) start[1313450866495] time[0]
>> tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
>> 2011-08-16 09:27:46,495 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Rslt:    ome.model.meta.Session:Id_6676
>> 2011-08-16 09:27:46,507 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-7) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/290df595-e731-4ccf-b171-4536a9f34c42omero.api.IAdmin(omero.api._IAdminTie at 5eb3a680
>> )
>> 2011-08-16 09:27:46,509 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Meth:    interface ome.api.IAdmin.getEventContext
>> 2011-08-16 09:27:46,509 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Args:    ()
>> 2011-08-16 09:27:46,511 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-7)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,511 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.reload(ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> 2011-08-16 09:27:46,511 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:46,517 INFO  [         ome.security.basic.EventHandler]
>> (-thread-24)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:46,524 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-2) start[1313450866464] time[60]
>> tag[omero.call.success.ome.services.blitz.impl.TimelineI$4.doWork]
>> 2011-08-16 09:27:46,524 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Rslt:    {Image=()}
>> 2011-08-16 09:27:46,534 INFO  [                 org.perf4j.TimingLogger]
>> (-thread-24) start[1313450866511] time[23]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
>> 2011-08-16 09:27:46,534 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Rslt:    (ome.model.meta.Experimenter:Id_652,
>> ome.model.meta.ExperimenterGroup:Id_203, [203, 205, 204, 207, 206, 216, 217,
>> 218, 219, 212, 213, 214, 215, 208, 209, 210, 211, 1], ... 4 more)
>> 2011-08-16 09:27:46,535 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-7) start[1313450866509] time[26]
>> tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
>> 2011-08-16 09:27:46,535 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Rslt:    ome.system.SimpleEventContext at 503654ed
>> 2011-08-16 09:27:46,539 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Meth:    interface ome.api.IAdmin.getExperimenter
>> 2011-08-16 09:27:46,539 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Args:    [652]
>> 2011-08-16 09:27:46,540 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-0)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,547 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-0) start[1313450866539] time[8]
>> tag[omero.call.success.ome.logic.AdminImpl.getExperimenter]
>> 2011-08-16 09:27:46,547 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Rslt:    ome.model.meta.Experimenter:Id_652
>> 2011-08-16 09:27:46,566 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Meth:    interface ome.api.IAdmin.lookupGroup
>> 2011-08-16 09:27:46,566 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Args:    [user]
>> 2011-08-16 09:27:46,568 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-8)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,576 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-8) start[1313450866566] time[10]
>> tag[omero.call.success.ome.logic.AdminImpl.lookupGroup]
>> 2011-08-16 09:27:46,576 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-8)  Rslt:    ome.model.meta.ExperimenterGroup:Id_1
>> 2011-08-16 09:27:46,607 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-6) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/290df595-e731-4ccf-b171-4536a9f34c42omero.api.IQuery(omero.api._IQueryTie at 6d735d78
>> )
>> 2011-08-16 09:27:46,609 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Meth:    interface ome.api.IQuery.findAllByQuery
>> 2011-08-16 09:27:46,610 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Args:    [select distinct obj from ExperimenterGroup as obj
>> left outer join fetch obj.groupExperimenterMap as map left outer join fetch
>> map.child e where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
>> 2011-08-16 09:27:46,611 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-6)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,615 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-6) start[1313450866610] time[5]
>> tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
>> 2011-08-16 09:27:46,616 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_203)
>> 2011-08-16 09:27:46,620 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Meth:    interface ome.api.IQuery.findAllByQuery
>> 2011-08-16 09:27:46,620 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Args:    [select e from ExperimenterGroup as e where e.id in
>> (:ids), PARAMS:ids=ArrayList(17) ]
>> 2011-08-16 09:27:46,621 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-3)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,624 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-3) start[1313450866620] time[4]
>> tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
>> 2011-08-16 09:27:46,625 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_203,
>> ome.model.meta.ExperimenterGroup:Id_204,
>> ome.model.meta.ExperimenterGroup:Id_205, ... 14 more)
>> 2011-08-16 09:27:46,633 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-1) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/290df595-e731-4ccf-b171-4536a9f34c42omero.api.ITimeline(omero.api._ITimelineTie at dd70843
>> )
>> 2011-08-16 09:27:46,635 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Executor.doWork --
>> ome.services.blitz.impl.TimelineI.getMostRecentAnnotationLinks
>> 2011-08-16 09:27:46,636 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:46,636 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-1)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,654 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-2) Found session locally: ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,655 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-2) Rejoining session
>> ServiceFactoryI(session-f5e4ff14-0f2f-4f71-a7be-00f666ba3800/ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> (agent=OMERO.web)
>> 2011-08-16 09:27:46,661 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-0) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/f5e4ff14-0f2f-4f71-a7be-00f666ba3800omero.api.ISession(omero.api._ISessionTie at 39f8cad0
>> )
>> 2011-08-16 09:27:46,663 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Meth:    interface ome.api.ISession.getSession
>> 2011-08-16 09:27:46,663 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Args:    [ff2e7caf-f1dd-4df4-b6f3-7cc014619816]
>> 2011-08-16 09:27:46,663 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-0) start[1313450866663] time[0]
>> tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
>> 2011-08-16 09:27:46,663 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Rslt:    ome.model.meta.Session:Id_6676
>> 2011-08-16 09:27:46,680 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-0) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/f5e4ff14-0f2f-4f71-a7be-00f666ba3800omero.api.IAdmin(omero.api._IAdminTie at 166ac96b
>> )
>> 2011-08-16 09:27:46,682 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Meth:    interface ome.api.IAdmin.getEventContext
>> 2011-08-16 09:27:46,682 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Args:    ()
>> 2011-08-16 09:27:46,683 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-0)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,684 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.reload(ff2e7caf-f1dd-4df4-b6f3-7cc014619816)
>> 2011-08-16 09:27:46,684 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:46,684 INFO  [         ome.security.basic.EventHandler]
>> (-thread-24)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:27:46,692 INFO  [                 org.perf4j.TimingLogger]
>> (-thread-24) start[1313450866684] time[8]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
>> 2011-08-16 09:27:46,692 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-24)  Rslt:    (ome.model.meta.Experimenter:Id_652,
>> ome.model.meta.ExperimenterGroup:Id_203, [203, 205, 204, 207, 206, 216, 217,
>> 218, 219, 212, 213, 214, 215, 208, 209, 210, 211, 1], ... 4 more)
>> 2011-08-16 09:27:46,692 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-0) start[1313450866682] time[10]
>> tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
>> 2011-08-16 09:27:46,692 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Rslt:    ome.system.SimpleEventContext at 762d754d
>> 2011-08-16 09:27:46,695 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Meth:    interface ome.api.IAdmin.getExperimenter
>> 2011-08-16 09:27:46,695 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Args:    [652]
>> 2011-08-16 09:27:46,696 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-4)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,702 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-4) start[1313450866695] time[7]
>> tag[omero.call.success.ome.logic.AdminImpl.getExperimenter]
>> 2011-08-16 09:27:46,702 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Rslt:    ome.model.meta.Experimenter:Id_652
>> 2011-08-16 09:27:46,719 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-1) start[1313450866636] time[83]
>> tag[omero.call.success.ome.services.blitz.impl.TimelineI$5.doWork]
>> 2011-08-16 09:27:46,719 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-1)  Rslt:    ()
>> 2011-08-16 09:27:46,721 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Meth:    interface ome.api.IAdmin.lookupGroup
>> 2011-08-16 09:27:46,721 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Args:    [user]
>> 2011-08-16 09:27:46,722 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-7)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,730 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-7) start[1313450866721] time[9]
>> tag[omero.call.success.ome.logic.AdminImpl.lookupGroup]
>> 2011-08-16 09:27:46,730 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Rslt:    ome.model.meta.ExperimenterGroup:Id_1
>> 2011-08-16 09:27:46,765 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-2) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/f5e4ff14-0f2f-4f71-a7be-00f666ba3800omero.api.IQuery(omero.api._IQueryTie at 5deed809
>> )
>> 2011-08-16 09:27:46,767 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-9)  Meth:    interface ome.api.IQuery.findAllByQuery
>> 2011-08-16 09:27:46,768 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-9)  Args:    [select distinct obj from ExperimenterGroup as obj
>> left outer join fetch obj.groupExperimenterMap as map left outer join fetch
>> map.child e where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
>> 2011-08-16 09:27:46,769 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-9)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,773 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-9) start[1313450866768] time[5]
>> tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
>> 2011-08-16 09:27:46,773 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-9)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_203)
>> 2011-08-16 09:27:46,778 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Meth:    interface ome.api.IQuery.findAllByQuery
>> 2011-08-16 09:27:46,778 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Args:    [select e from ExperimenterGroup as e where e.id in
>> (:ids), PARAMS:ids=ArrayList(17) ]
>> 2011-08-16 09:27:46,779 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-5)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,782 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-5) start[1313450866778] time[4]
>> tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
>> 2011-08-16 09:27:46,782 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-5)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_203,
>> ome.model.meta.ExperimenterGroup:Id_204,
>> ome.model.meta.ExperimenterGroup:Id_205, ... 14 more)
>> 2011-08-16 09:27:46,792 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-6) Added servant to adapter:
>> ff2e7caf-f1dd-4df4-b6f3-7cc014619816/f5e4ff14-0f2f-4f71-a7be-00f666ba3800omero.api.ITimeline(omero.api._ITimelineTie at 63835933
>> )
>> 2011-08-16 09:27:46,795 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Executor.doWork --
>> ome.services.blitz.impl.TimelineI.getMostRecentShareComments
>> 2011-08-16 09:27:46,795 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:46,796 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-6)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,800 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-6) start[1313450866795] time[5]
>> tag[omero.call.success.ome.services.blitz.impl.TimelineI$6.doWork]
>> 2011-08-16 09:27:46,800 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Rslt:    ()
>> 2011-08-16 09:27:46,801 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Executor.doWork --
>> ome.services.blitz.impl.TimelineI.getMostRecentShareComments
>> 2011-08-16 09:27:46,801 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:27:46,802 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-0)  Auth:
>> user=652,group=203,event=null(User),sess=ff2e7caf-f1dd-4df4-b6f3-7cc014619816
>> 2011-08-16 09:27:46,805 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-0) start[1313450866801] time[4]
>> tag[omero.call.success.ome.services.blitz.impl.TimelineI$6.doWork]
>> 2011-08-16 09:27:46,805 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Rslt:    ()
>> 
>> 
>> But the user from another OU can't login and I can see in the logs:
>> 
>> 2011-08-16 09:33:15,853 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-9) start[1313451192770] time[3083]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$9.doWork]
>> 2011-08-16 09:33:15,854 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-9)  Rslt:    false
>> 2011-08-16 09:33:15,872 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest)
>> 2011-08-16 09:33:15,872 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:33:15,883 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-3)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:33:15,925 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-3) start[1313451195872] time[53]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
>> 2011-08-16 09:33:15,925 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Rslt:    true
>> 2011-08-16 09:33:15,927 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.createSession
>> 2011-08-16 09:33:15,927 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:33:15,941 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-6)  Auth:
>> user=0,group=0,event=60625(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:33:15,951 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-6) Adding log:INSERT,class ome.model.meta.Session,6678
>> 2011-08-16 09:33:15,960 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-6) start[1313451195927] time[33]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
>> 2011-08-16 09:33:15,960 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Rslt:    (ome.model.meta.Experimenter:Id_1,
>> ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
>> 2011-08-16 09:33:15,962 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-6) Created session
>> ServiceFactoryI(session-464d7db3-770e-46ca-aa42-730901b15043/cff5aec8-ced6-4015-8071-f80a2f8f28a3)
>> for user guest (agent=OMERO.web)
>> 2011-08-16 09:33:15,967 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-4) Added servant to adapter:
>> cff5aec8-ced6-4015-8071-f80a2f8f28a3/464d7db3-770e-46ca-aa42-730901b15043omero.api.ISession(omero.api._ISessionTie at 6f60caf6
>> )
>> 2011-08-16 09:33:15,968 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Meth:    interface ome.api.ISession.getSession
>> 2011-08-16 09:33:15,968 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Args:    [cff5aec8-ced6-4015-8071-f80a2f8f28a3]
>> 2011-08-16 09:33:15,968 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-4) start[1313451195968] time[0]
>> tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
>> 2011-08-16 09:33:15,968 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Rslt:    ome.model.meta.Session:Id_6678
>> 2011-08-16 09:33:15,976 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-4) Added servant to adapter:
>> cff5aec8-ced6-4015-8071-f80a2f8f28a3/464d7db3-770e-46ca-aa42-730901b15043omero.api.IAdmin(omero.api._IAdminTie at 5eaae658
>> )
>> 2011-08-16 09:33:15,978 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Meth:    interface ome.api.IAdmin.getEventContext
>> 2011-08-16 09:33:15,978 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Args:    ()
>> 2011-08-16 09:33:15,979 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-4)  Auth:
>> user=1,group=2,event=null(User),sess=cff5aec8-ced6-4015-8071-f80a2f8f28a3
>> 2011-08-16 09:33:15,979 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-25)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.reload(cff5aec8-ced6-4015-8071-f80a2f8f28a3)
>> 2011-08-16 09:33:15,980 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-25)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:33:15,981 INFO  [         ome.security.basic.EventHandler]
>> (-thread-25)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:33:15,987 INFO  [                 org.perf4j.TimingLogger]
>> (-thread-25) start[1313451195980] time[7]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
>> 2011-08-16 09:33:15,987 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-25)  Rslt:    (ome.model.meta.Experimenter:Id_1,
>> ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
>> 2011-08-16 09:33:15,988 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-4) start[1313451195978] time[10]
>> tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
>> 2011-08-16 09:33:15,988 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-4)  Rslt:    ome.system.SimpleEventContext at 59de6ee9
>> 2011-08-16 09:33:15,991 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-7) Added servant to adapter:
>> cff5aec8-ced6-4015-8071-f80a2f8f28a3/464d7db3-770e-46ca-aa42-730901b15043omero.api.IConfig(omero.api._IConfigTie at 72b6f43b
>> )
>> 2011-08-16 09:33:15,992 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Meth:    interface ome.api.IConfig.getConfigValue
>> 2011-08-16 09:33:15,993 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Args:    [omero.version]
>> 2011-08-16 09:33:16,005 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-7)  Auth:
>> user=1,group=2,event=60626(User),sess=cff5aec8-ced6-4015-8071-f80a2f8f28a3
>> 2011-08-16 09:33:16,009 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-7) start[1313451195993] time[16]
>> tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
>> 2011-08-16 09:33:16,009 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-7)  Rslt:    4.3.1-6d4b8c61-b2602
>> 2011-08-16 09:33:16,038 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest)
>> 2011-08-16 09:33:16,038 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:33:16,039 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-3)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:33:16,042 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-3) start[1313451196038] time[4]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
>> 2011-08-16 09:33:16,042 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Rslt:    true
>> 2011-08-16 09:33:16,043 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.createSession
>> 2011-08-16 09:33:16,043 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Args:    [null, InternalSF at 1940813045]
>> 
>> 2011-08-16 09:33:16,057 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-6)  Auth:
>> user=0,group=0,event=60627(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:33:16,067 INFO  [       ome.security.basic.CurrentDetails]
>> (l.Server-6) Adding log:INSERT,class ome.model.meta.Session,6679
>> 2011-08-16 09:33:16,076 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-6) start[1313451196043] time[33]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
>> 2011-08-16 09:33:16,076 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-6)  Rslt:    (ome.model.meta.Experimenter:Id_1,
>> ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
>> 2011-08-16 09:33:16,079 INFO  [ ome.services.blitz.fire.SessionManagerI]
>> (l.Server-6) Created session
>> ServiceFactoryI(session-f7794d81-6201-4646-b21f-000aa88354d7/602ef3dd-67e1-4a50-8203-851081ca2155)
>> for user guest (agent=OMERO.web)
>> 2011-08-16 09:33:16,084 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-0) Added servant to adapter:
>> 602ef3dd-67e1-4a50-8203-851081ca2155/f7794d81-6201-4646-b21f-000aa88354d7omero.api.ISession(omero.api._ISessionTie at 168b1754
>> )
>> 2011-08-16 09:33:16,085 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Meth:    interface ome.api.ISession.getSession
>> 2011-08-16 09:33:16,085 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Args:    [602ef3dd-67e1-4a50-8203-851081ca2155]
>> 2011-08-16 09:33:16,085 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-0) start[1313451196085] time[0]
>> tag[omero.call.success.ome.services.sessions.SessionBean.getSession]
>> 2011-08-16 09:33:16,085 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-0)  Rslt:    ome.model.meta.Session:Id_6679
>> 2011-08-16 09:33:16,094 INFO  [e.services.sessions.SessionContext$Count]
>> (l.Server-2) -Reference count: 44e21875-fa6e-411e-a6c4-6748a60a3400=0
>> 2011-08-16 09:33:16,098 INFO  [e.services.sessions.SessionContext$Count]
>> (l.Server-2) -Reference count: cff5aec8-ced6-4015-8071-f80a2f8f28a3=0
>> 2011-08-16 09:33:16,102 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-2) Added servant to adapter:
>> 602ef3dd-67e1-4a50-8203-851081ca2155/f7794d81-6201-4646-b21f-000aa88354d7omero.api.IAdmin(omero.api._IAdminTie at 462ba2ad
>> )
>> 2011-08-16 09:33:16,103 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Meth:    interface ome.api.IAdmin.getEventContext
>> 2011-08-16 09:33:16,103 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Args:    ()
>> 2011-08-16 09:33:16,105 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-2)  Auth:
>> user=1,group=2,event=null(User),sess=602ef3dd-67e1-4a50-8203-851081ca2155
>> 2011-08-16 09:33:16,105 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-25)  Executor.doWork --
>> ome.services.sessions.SessionManagerImpl.reload(602ef3dd-67e1-4a50-8203-851081ca2155)
>> 2011-08-16 09:33:16,105 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-25)  Args:    [null, InternalSF at 1940813045]
>> 2011-08-16 09:33:16,106 INFO  [         ome.security.basic.EventHandler]
>> (-thread-25)  Auth:
>> user=0,group=0,event=null(Sessions),sess=ea945943-81fd-4534-9fb9-bca90eb78006
>> 2011-08-16 09:33:16,112 INFO  [                 org.perf4j.TimingLogger]
>> (-thread-25) start[1313451196105] time[7]
>> tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
>> 2011-08-16 09:33:16,112 INFO  [        ome.services.util.ServiceHandler]
>> (-thread-25)  Rslt:    (ome.model.meta.Experimenter:Id_1,
>> ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
>> 2011-08-16 09:33:16,113 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-2) start[1313451196104] time[9]
>> tag[omero.call.success.ome.logic.AdminImpl.getEventContext]
>> 2011-08-16 09:33:16,113 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-2)  Rslt:    ome.system.SimpleEventContext at 6fcdf64a
>> 2011-08-16 09:33:16,116 INFO  [ ome.services.blitz.impl.ServiceFactoryI]
>> (l.Server-3) Added servant to adapter:
>> 602ef3dd-67e1-4a50-8203-851081ca2155/f7794d81-6201-4646-b21f-000aa88354d7omero.api.IConfig(omero.api._IConfigTie at 960cf8e
>> )
>> 2011-08-16 09:33:16,118 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Meth:    interface ome.api.IConfig.getConfigValue
>> 2011-08-16 09:33:16,118 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Args:    [omero.version]
>> 2011-08-16 09:33:16,131 INFO  [         ome.security.basic.EventHandler]
>> (l.Server-3)  Auth:
>> user=1,group=2,event=60628(User),sess=602ef3dd-67e1-4a50-8203-851081ca2155
>> 2011-08-16 09:33:16,135 INFO  [                 org.perf4j.TimingLogger]
>> (l.Server-3) start[1313451196118] time[17]
>> tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
>> 2011-08-16 09:33:16,135 INFO  [        ome.services.util.ServiceHandler]
>> (l.Server-3)  Rslt:    4.3.1-6d4b8c61-b2602
>> 
>> 
>> Any ideas?
>> 
>> Thanks,
>> Leon Kolchinsky
>> 
>> 
>> 
>> On Tue, Aug 16, 2011 at 00:38, Josh Moore <josh at glencoesoftware.com>wrote:
>> 
>>> Hi Leon,
>>> 
>>> That groups are not synchronized for existing users is expected. The group
>>> values are only consulted for new users (hence the name). Could you possibly
>>> send us your var/log/Blitz-0.log so we can figure out why new users can't
>>> login?
>>> 
>>> Thanks,
>>> ~Josh.
>>> 
>>> 
>>> 
>>> 
>>> On Aug 15, 2011, at 4:31 PM, Leon Kolchinsky wrote:
>>> 
>>>> Hello Josh,
>>>> 
>>>> omero.ldap.new_user_group=:query:(uniquemember=@{dn})
>>>> Groups are not pulled for existing users.
>>>> 
>>>> New users can't even login ;(
>>>> Although they could login on a clean installation.
>>>> 
>>>> Cheers,
>>>> Leon Kolchinsky
>>>> 
>>>> 
>>>> 
>>>> On Tue, Aug 16, 2011 at 00:14, Josh Moore <josh at glencoesoftware.com>
>>> wrote:
>>>> 
>>>>> Hi Leon,
>>>>> 
>>>>> what is the value of omero.ldap.new_user_group that you're using? Also,
>>> is
>>>>> the problem you're seeing (i.e. groups not being pulled for users) on
>>> new
>>>>> users or existing users?
>>>>> 
>>>>> Cheers,
>>>>> ~Josh.
>>>>> 
>>>>> 
>>>>> On Aug 15, 2011, at 3:42 PM, Leon Kolchinsky wrote:
>>>>> 
>>>>>> Hello,
>>>>>> 
>>>>>> I've installed 4.3.1 version of OMERO.
>>>>>> It was configured to pull groups of logged in users from LDAP and it
>>> worked!
>>>>>> 
>>>>>> I've dumped DB of 4.2.2 version from another server, restored it on a
>>> new
>>>>>> one.
>>>>>> Upgraded this DB with the script on your site and moved all data to
>>> the new
>>>>>> server.
>>>>>> I've re-run all "/srv/omeroserver/bin/omero config set" instructions
>>> again
>>>>>> but the groups of the users aren't pulled from the LDAP ;(
>>>>>> 
>>>>>> Running "omero config get" shows me the right configuration options.
>>>>>> 
>>>>>> Is there a configuration in the DB I need to configure/delete?
>>>>>> 
>>>>>> What could be the reason for that behavior?
>>>>>> 
>>>>>> Cheers,
>>>>>> Leon Kolchinsky
>>> 
>>> 
>> 

-------------- next part --------------
A non-text attachment was scrubbed...
Name: PGP.sig
Type: application/pgp-signature
Size: 243 bytes
Desc: This is a digitally signed message part
URL: <http://lists.openmicroscopy.org.uk/pipermail/ome-users/attachments/20110816/8ac352e3/attachment.sig>


More information about the ome-users mailing list