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

Josh Moore josh at glencoesoftware.com
Wed Aug 17 09:12:54 BST 2011


Hi Leon,

you'll see I've added you to the new wishlist item #6502 (complex new_user_group configs). You might also take a look at #6501 (ldap synchronization) which is available on a branch if you'd be able to test for us.

Cheers,
~Josh.

 *  https://trac.openmicroscopy.org.uk/ome/ticket/6501
 *  https://trac.openmicroscopy.org.uk/ome/ticket/6502

On Aug 17, 2011, at 3:33 AM, Leon Kolchinsky wrote:

> Hello Josh,
> 
> 
> On Tue, Aug 16, 2011 at 16:46, Josh Moore <josh at glencoesoftware.com> wrote:
> 
>> 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.
>> 
> Yep, I can confirm ;)
> Now I've disabled group pulling capability, external users (those without
> groups) can login via web client:
> 
> Would it be be possible to add the following to the wishlist:
> If groups for a particular user in LDAP are not found he will be assigned to
> a default group:
> as it is now - (omero.ldap.new_user_group=default)
> and this group will take precedence, i.e. will be assigned first and lookup
> for additional groups (LDAP groups) will continue (successfully or not) and
> not prevent the user from logging in.
> 
> 
>> 
>>> 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/1382links 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.
>> 
> Thanks for the info ;)
> 
> 
>> 
>>> Cheers,
>>> Leon Kolchinsky
>> 
>> Cheers,
>> ~Josh
>> 
>> 
> Thank you very much,
> Leon Kolchinsky
> 
> 
>>> 
>>> 
>>> 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.idin
>>>> (: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.idin
>>>> (: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.idin
>>>> (: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.idin
>>>> (: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/20110817/20d98fef/attachment.sig>


More information about the ome-users mailing list