[ome-users] Error (EXTERNAL IP): /webadmin/load_drivespace/

Leon Kolchinsky lkolchin at gmail.com
Wed Sep 21 13:39:25 BST 2011


Hello Will,

Actually the DB is full of images and I'm not getting the previous e-mails
anymore.
But I still can't see usage stats ;(
I suspect that it's connected somehow to the new LDAP modules in ver. 4.3.2.
Since new modules checking for users in the LDAP every time they log-in into
the system, I presume that the mechanism implemented here the same.
No users found and it's just failing ;(


I'm attaching screenshot.
Those 2 users displayed aren't actually in our LDAP anymore.
They left several months ago but their details still in OMERO DB.

Here is a snap from Blitz-0.log:

2011-09-21 22:27:12,659 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:27:12,664 INFO  [         ome.security.basic.EventHandler]
(3-thread-2)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:27:12,671 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608032660] time[11]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:27:12,671 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:27:12,672 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(a8fae106-e386-441d-89ce-8b76358d6571)
2011-09-21 22:27:12,672 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:27:12,673 INFO  [         ome.security.basic.EventHandler]
(3-thread-2)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:27:12,683 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608032672] time[11]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:27:12,683 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:27:12,684 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(00aeeeb1-0de5-4090-91f6-5f801a2b2d9c)
2011-09-21 22:27:12,684 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:27:12,686 INFO  [         ome.security.basic.EventHandler]
(3-thread-2)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:27:12,694 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608032686] time[8]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:27:12,695 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Rslt:    (ome.model.meta.Experimenter:Id_1,
ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
2011-09-21 22:27:12,695 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608032635] time[60]
tag[omero.sessions.synchronization]
2011-09-21 22:27:12,695 INFO  [ome.services.sessions.state.SessionCache]
(3-thread-2) Synchronization took 60 ms.
2011-09-21 22:29:12,632 INFO  [ome.services.sessions.state.SessionCache]
(3-thread-2) Synchronizing session cache. Count = 5
2011-09-21 22:29:12,632 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(760f5b02-5745-4f8b-91b5-0b96db53aaca)
2011-09-21 22:29:12,632 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:29:12,642 INFO  [         ome.security.basic.EventHandler]
(3-thread-2)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:29:12,658 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608152632] time[26]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:29:12,658 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:29:12,659 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(aca27771-5fb5-4bc7-91ae-29009b9d2be2)
2011-09-21 22:29:12,659 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:29:12,660 INFO  [         ome.security.basic.EventHandler]
(3-thread-2)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:29:12,672 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608152659] time[13]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:29:12,672 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:29:12,672 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(ddf0f78c-175f-498c-9abd-92e68f9bf8fd)
2011-09-21 22:29:12,672 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:29:12,673 INFO  [         ome.security.basic.EventHandler]
(3-thread-2)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:29:12,681 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608152672] time[9]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:29:12,681 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:29:12,682 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(a8fae106-e386-441d-89ce-8b76358d6571)
2011-09-21 22:29:12,682 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:29:12,683 INFO  [         ome.security.basic.EventHandler]
(3-thread-2)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:29:12,692 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608152682] time[10]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:29:12,692 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:29:12,692 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(00aeeeb1-0de5-4090-91f6-5f801a2b2d9c)
2011-09-21 22:29:12,692 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:29:12,696 INFO  [         ome.security.basic.EventHandler]
(3-thread-2)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:29:12,703 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608152692] time[11]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:29:12,703 INFO  [        ome.services.util.ServiceHandler]
(3-thread-2)  Rslt:    (ome.model.meta.Experimenter:Id_1,
ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
2011-09-21 22:29:12,703 INFO  [                 org.perf4j.TimingLogger]
(3-thread-2) start[1316608152632] time[71]
tag[omero.sessions.synchronization]
2011-09-21 22:29:12,703 INFO  [ome.services.sessions.state.SessionCache]
(3-thread-2) Synchronization took 71 ms.
2011-09-21 22:29:46,688 INFO  [        ome.services.util.ServiceHandler]
(l.Server-7)  Meth:    interface ome.api.IQuery.findAllByQuery
2011-09-21 22:29:46,688 INFO  [        ome.services.util.ServiceHandler]
(l.Server-7)  Args:    [select g from ExperimenterGroup as g where
g.namenot in (:default_names), PARAMS:F(l1):default_names=ArrayList(3)
]
2011-09-21 22:29:46,692 INFO  [         ome.security.basic.EventHandler]
(l.Server-7)  Auth:
 user=0,group=0,event=null(User),sess=760f5b02-5745-4f8b-91b5-0b96db53aaca
2011-09-21 22:29:46,697 INFO  [                 org.perf4j.TimingLogger]
(l.Server-7) start[1316608186688] time[9]
tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2011-09-21 22:29:46,697 INFO  [        ome.services.util.ServiceHandler]
(l.Server-7)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_4)
2011-09-21 22:29:46,703 INFO  [        ome.services.util.ServiceHandler]
(l.Server-9)  Meth:    interface ome.api.IQuery.findAllByQuery
2011-09-21 22:29:46,703 INFO  [        ome.services.util.ServiceHandler]
(l.Server-9)  Args:    [select distinct obj from Experimenter as obj left
outer join fetch obj.groupExperimenterMap as map left outer join fetch
map.parent g, PARAMS]
2011-09-21 22:29:46,705 INFO  [         ome.security.basic.EventHandler]
(l.Server-9)  Auth:
 user=0,group=0,event=null(User),sess=760f5b02-5745-4f8b-91b5-0b96db53aaca
2011-09-21 22:29:46,721 INFO  [                 org.perf4j.TimingLogger]
(l.Server-9) start[1316608186703] time[18]
tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2011-09-21 22:29:46,721 INFO  [        ome.services.util.ServiceHandler]
(l.Server-9)  Rslt:    (ome.model.meta.Experimenter:Id_103,
ome.model.meta.Experimenter:Id_257, ome.model.meta.Experimenter:Id_152, ...
40 more)
2011-09-21 22:29:46,810 INFO  [        ome.services.util.ServiceHandler]
(l.Server-7)  Meth:    interface ome.api.IAdmin.lookupLdapAuthExperimenters
2011-09-21 22:29:46,810 INFO  [        ome.services.util.ServiceHandler]
(l.Server-7)  Args:    ()
2011-09-21 22:29:46,813 INFO  [         ome.security.basic.EventHandler]
(l.Server-7)  Auth:
 user=0,group=0,event=null(User),sess=760f5b02-5745-4f8b-91b5-0b96db53aaca
2011-09-21 22:29:46,816 INFO  [                 org.perf4j.TimingLogger]
(l.Server-7) start[1316608186810] time[6]
tag[omero.call.success.ome.logic.AdminImpl.lookupLdapAuthExperimenters]
2011-09-21 22:29:46,816 INFO  [        ome.services.util.ServiceHandler]
(l.Server-7)  Rslt:    ({dn=cn=Russell Sim,ou=Monash e-Research
Centre,ou=Administration,ou=Staff,o=Monash University,c=au,
experimenter_id=2}, {dn=cn=Raymond Lau,ou=Monash e-Research
Centre,ou=Administration,ou=Staff,o=Monash University,c=au,
experimenter_id=52}, {dn=cn=Nigel Holdgate,ou=Monash e-Research
Centre,ou=Administration,ou=Staff,o=Monash University,c=au,
experimenter_id=53}, ... 29 more)
2011-09-21 22:29:56,308 INFO  [        ome.services.util.ServiceHandler]
(l.Server-1)  Meth:    interface ome.api.IQuery.findAllByQuery
2011-09-21 22:29:56,308 INFO  [        ome.services.util.ServiceHandler]
(l.Server-1)  Args:    [select g from ExperimenterGroup as g where
g.namenot in (:default_names), PARAMS:F(l1):default_names=ArrayList(3)
]
2011-09-21 22:29:56,311 INFO  [         ome.security.basic.EventHandler]
(l.Server-1)  Auth:
 user=0,group=0,event=null(User),sess=760f5b02-5745-4f8b-91b5-0b96db53aaca
2011-09-21 22:29:56,314 INFO  [                 org.perf4j.TimingLogger]
(l.Server-1) start[1316608196308] time[6]
tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2011-09-21 22:29:56,314 INFO  [        ome.services.util.ServiceHandler]
(l.Server-1)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_4)
2011-09-21 22:29:56,319 INFO  [        ome.services.util.ServiceHandler]
(l.Server-2)  Meth:    interface ome.api.IQuery.findByQuery
2011-09-21 22:29:56,319 INFO  [        ome.services.util.ServiceHandler]
(l.Server-2)  Args:    [select distinct obj from Experimenter as obj left
outer join fetch obj.groupExperimenterMap as map left outer join fetch
map.parent g where obj.id in (:ids), PARAMS:ids=ArrayList(1) ]
2011-09-21 22:29:56,321 INFO  [         ome.security.basic.EventHandler]
(l.Server-2)  Auth:
 user=0,group=0,event=null(User),sess=760f5b02-5745-4f8b-91b5-0b96db53aaca
2011-09-21 22:29:56,327 INFO  [                 org.perf4j.TimingLogger]
(l.Server-2) start[1316608196319] time[8]
tag[omero.call.success.ome.logic.QueryImpl.findByQuery]
2011-09-21 22:29:56,327 INFO  [        ome.services.util.ServiceHandler]
(l.Server-2)  Rslt:    ome.model.meta.Experimenter:Id_53
2011-09-21 22:29:56,333 INFO  [        ome.services.util.ServiceHandler]
(l.Server-0)  Meth:    interface ome.api.IAdmin.lookupLdapAuthExperimenter
2011-09-21 22:29:56,333 INFO  [        ome.services.util.ServiceHandler]
(l.Server-0)  Args:    [53]
2011-09-21 22:29:56,334 INFO  [         ome.security.basic.EventHandler]
(l.Server-0)  Auth:
 user=0,group=0,event=null(User),sess=760f5b02-5745-4f8b-91b5-0b96db53aaca
2011-09-21 22:29:56,340 INFO  [                 org.perf4j.TimingLogger]
(l.Server-0) start[1316608196333] time[7]
tag[omero.call.success.ome.logic.AdminImpl.lookupLdapAuthExperimenter]
2011-09-21 22:29:56,340 INFO  [        ome.services.util.ServiceHandler]
(l.Server-0)  Rslt:    cn=Nigel Holdgate,ou=Monash e-Research
Centre,ou=Administration,ou=Staff,o=Monash University,c=au
2011-09-21 22:29:56,341 INFO  [        ome.services.util.ServiceHandler]
(l.Server-8)  Meth:    interface ome.api.IQuery.findAllByQuery
2011-09-21 22:29:56,341 INFO  [        ome.services.util.ServiceHandler]
(l.Server-8)  Args:    [select distinct obj from ExperimenterGroup as obj
left outer join fetch obj.groupExperimenterMap as map left outer join fetch
map.child e, PARAMS]
2011-09-21 22:29:56,343 INFO  [         ome.security.basic.EventHandler]
(l.Server-8)  Auth:
 user=0,group=0,event=null(User),sess=760f5b02-5745-4f8b-91b5-0b96db53aaca
2011-09-21 22:29:56,357 INFO  [                 org.perf4j.TimingLogger]
(l.Server-8) start[1316608196341] time[16]
tag[omero.call.success.ome.logic.QueryImpl.findAllByQuery]
2011-09-21 22:29:56,357 INFO  [        ome.services.util.ServiceHandler]
(l.Server-8)  Rslt:    (ome.model.meta.ExperimenterGroup:Id_53,
ome.model.meta.ExperimenterGroup:Id_4,
ome.model.meta.ExperimenterGroup:Id_154, ... 6 more)
2011-09-21 22:30:00,024 INFO  [ ome.services.blitz.fire.SessionManagerI]
(3-thread-4) Performing requestHeartbeats
2011-09-21 22:30:13,018 INFO  [            ome.services.blitz.fire.Ring]
(3-thread-5) Checking cluster
2011-09-21 22:30:13,019 INFO  [        ome.services.blitz.fire.Registry]
(3-thread-5) Found 1 cluster node(s) :
[ClusterNode/ddf0f78c-175f-498c-9abd-92e68f9bf8fd -t:tcp -h 130.194.245.188
-p 52943]
2011-09-21 22:30:13,023 INFO  [            ome.services.blitz.fire.Ring]
(3-thread-5) Got 1 cluster uuids : [ddf0f78c-175f-498c-9abd-92e68f9bf8fd]
2011-09-21 22:30:13,025 INFO  [        ome.services.util.ServiceHandler]
(3-thread-5)  Executor.doWork -- ome.services.blitz.fire.Ring.getManagerList
2011-09-21 22:30:13,025 INFO  [        ome.services.util.ServiceHandler]
(3-thread-5)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:30:13,025 INFO  [         ome.security.basic.EventHandler]
(3-thread-5)  Auth:
 user=0,group=0,event=null(Internal),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:30:13,037 INFO  [                 org.perf4j.TimingLogger]
(3-thread-5) start[1316608213025] time[12]
tag[omero.call.success.ome.services.blitz.fire.Ring$2.doWork]
2011-09-21 22:30:13,040 INFO  [        ome.services.util.ServiceHandler]
(3-thread-5)  Rslt:    (ddf0f78c-175f-498c-9abd-92e68f9bf8fd)
2011-09-21 22:31:12,646 INFO  [ome.services.sessions.state.SessionCache]
(3-thread-1) Synchronizing session cache. Count = 5
2011-09-21 22:31:12,646 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(760f5b02-5745-4f8b-91b5-0b96db53aaca)
2011-09-21 22:31:12,646 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:31:12,646 INFO  [         ome.security.basic.EventHandler]
(3-thread-1)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:31:12,657 INFO  [                 org.perf4j.TimingLogger]
(3-thread-1) start[1316608272646] time[11]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:31:12,657 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:31:12,657 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(aca27771-5fb5-4bc7-91ae-29009b9d2be2)
2011-09-21 22:31:12,658 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:31:12,660 INFO  [         ome.security.basic.EventHandler]
(3-thread-1)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:31:12,669 INFO  [                 org.perf4j.TimingLogger]
(3-thread-1) start[1316608272658] time[11]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:31:12,670 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:31:12,671 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(ddf0f78c-175f-498c-9abd-92e68f9bf8fd)
2011-09-21 22:31:12,671 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:31:12,673 INFO  [         ome.security.basic.EventHandler]
(3-thread-1)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:31:12,681 INFO  [                 org.perf4j.TimingLogger]
(3-thread-1) start[1316608272671] time[10]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:31:12,682 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:31:12,684 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(a8fae106-e386-441d-89ce-8b76358d6571)
2011-09-21 22:31:12,684 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:31:12,684 INFO  [         ome.security.basic.EventHandler]
(3-thread-1)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:31:12,692 INFO  [                 org.perf4j.TimingLogger]
(3-thread-1) start[1316608272684] time[8]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:31:12,692 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:31:12,693 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(00aeeeb1-0de5-4090-91f6-5f801a2b2d9c)
2011-09-21 22:31:12,693 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:31:12,695 INFO  [         ome.security.basic.EventHandler]
(3-thread-1)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:31:12,707 INFO  [                 org.perf4j.TimingLogger]
(3-thread-1) start[1316608272693] time[14]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:31:12,707 INFO  [        ome.services.util.ServiceHandler]
(3-thread-1)  Rslt:    (ome.model.meta.Experimenter:Id_1,
ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
2011-09-21 22:31:12,707 INFO  [                 org.perf4j.TimingLogger]
(3-thread-1) start[1316608272646] time[61]
tag[omero.sessions.synchronization]
2011-09-21 22:31:12,707 INFO  [ome.services.sessions.state.SessionCache]
(3-thread-1) Synchronization took 61 ms.
2011-09-21 22:33:00,014 INFO  [ ome.services.blitz.fire.SessionManagerI]
(3-thread-1) Performing requestHeartbeats
2011-09-21 22:33:12,649 INFO  [ome.services.sessions.state.SessionCache]
(3-thread-4) Synchronizing session cache. Count = 5
2011-09-21 22:33:12,649 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(760f5b02-5745-4f8b-91b5-0b96db53aaca)
2011-09-21 22:33:12,649 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:33:12,658 INFO  [         ome.security.basic.EventHandler]
(3-thread-4)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:33:12,674 INFO  [                 org.perf4j.TimingLogger]
(3-thread-4) start[1316608392649] time[25]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:33:12,674 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:33:12,675 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(aca27771-5fb5-4bc7-91ae-29009b9d2be2)
2011-09-21 22:33:12,675 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:33:12,677 INFO  [         ome.security.basic.EventHandler]
(3-thread-4)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:33:12,687 INFO  [                 org.perf4j.TimingLogger]
(3-thread-4) start[1316608392675] time[12]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:33:12,687 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:33:12,687 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(ddf0f78c-175f-498c-9abd-92e68f9bf8fd)
2011-09-21 22:33:12,687 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:33:12,689 INFO  [         ome.security.basic.EventHandler]
(3-thread-4)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:33:12,698 INFO  [                 org.perf4j.TimingLogger]
(3-thread-4) start[1316608392687] time[11]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:33:12,698 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:33:12,698 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(a8fae106-e386-441d-89ce-8b76358d6571)
2011-09-21 22:33:12,698 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:33:12,700 INFO  [         ome.security.basic.EventHandler]
(3-thread-4)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:33:12,708 INFO  [                 org.perf4j.TimingLogger]
(3-thread-4) start[1316608392698] time[10]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:33:12,709 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Rslt:    (ome.model.meta.Experimenter:Id_0,
ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2011-09-21 22:33:12,711 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Executor.doWork --
ome.services.sessions.SessionManagerImpl.reload(00aeeeb1-0de5-4090-91f6-5f801a2b2d9c)
2011-09-21 22:33:12,711 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Args:    [null, InternalSF at 1831491676]
2011-09-21 22:33:12,711 INFO  [         ome.security.basic.EventHandler]
(3-thread-4)  Auth:
 user=0,group=0,event=null(Sessions),sess=ddf0f78c-175f-498c-9abd-92e68f9bf8fd
2011-09-21 22:33:12,719 INFO  [                 org.perf4j.TimingLogger]
(3-thread-4) start[1316608392711] time[8]
tag[omero.call.success.ome.services.sessions.SessionManagerImpl$6.doWork]
2011-09-21 22:33:12,720 INFO  [        ome.services.util.ServiceHandler]
(3-thread-4)  Rslt:    (ome.model.meta.Experimenter:Id_1,
ome.model.meta.ExperimenterGroup:Id_2, [2], ... 4 more)
2011-09-21 22:33:12,720 INFO  [                 org.perf4j.TimingLogger]
(3-thread-4) start[1316608392649] time[71]
tag[omero.sessions.synchronization]
2011-09-21 22:33:12,720 INFO  [ome.services.sessions.state.SessionCache]
(3-thread-4) Synchronization took 71 ms.

Any patches?

Thanks,
Leon Kolchinsky



On Wed, Sep 21, 2011 at 21:54, Will Moore <will at lifesci.dundee.ac.uk> wrote:

> Hi Leon,
>
>  This appears to be a bug when trying to calculate the Drive Space usage
> when there are no images in the database.
>
> Hopefully you won't see this error once you have imported some images?
>
> Should be an easy fix anyway. I'll create a ticket..
>
>   Cheers,
>
>   Will.
>
>
> On 20 Sep 2011, at 13:52, Leon Kolchinsky wrote:
>
> Hello,
>
> I'm testing new version of Omero Server 4.3.2
> When logged-in as root to webadmin interface I click on "Drive Space" and
> get this message "Could not load piechart. Please contact Administrator".
> I'm also getting the following e-mail:
>
>
> Traceback (most recent call last):
>
>  File "/srv/omeroserver/lib/python/django/core/handlers/base.py", line 92,
> in get_response
>    response = callback(request, *callback_args, **callback_kwargs)
>
>  File "/srv/omeroserver/lib/python/omeroweb/webadmin/views.py", line 168,
> in wrapped
>    return f(request, *args, **kwargs)
>
>  File "/srv/omeroserver/lib/python/omeroweb/webadmin/views.py", line 871,
> in load_drivespace
>    rv = usersData(conn, offset)
>
>  File
> "/srv/omeroserver/lib/python/omeroweb/webadmin/controller/drivespace.py",
> line 99, in usersData
>    "where m.child.id in (:pids)", p2, ctx)
>
>  File "/srv/omeroserver/lib/python/omero/gateway/__init__.py", line 2959,
> in __call__
>    return self.handle_exception(e, *args, **kwargs)
>
>  File
> "/srv/OMERO.server-Beta-4.3.2/lib/python/omeroweb/webclient/webclient_gateway.py",
> line 1652, in handle_exception
>    e, *args, **kwargs)
>
>  File "/srv/omeroserver/lib/python/omero/gateway/__init__.py", line 2956,
> in __call__
>    return self.f(*args, **kwargs)
>
>  File "/srv/omeroserver/lib/python/omero_api_IQuery_ice.py", line 138, in
> findAllByQuery
>    return _M_omero.api.IQuery._op_findAllByQuery.invoke(self, ((query,
> params), _ctx))
>
> ApiUsageException: exception ::omero::ApiUsageException
> {
>    serverStackTrace = ome.conditions.ApiUsageException: unexpected end of
> subtree [select m from ome.model.core.PixelsOriginalFileMap as m join fetch
> m.parent where m.child.id in ()]; nested exception is
> org.hibernate.hql.ast.QuerySyntaxException: unexpected end of subtree
> [select m from ome.model.core.PixelsOriginalFileMap as m join fetch m.parent
> where m.child.id in ()]
>        at
> org.springframework.orm.hibernate3.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:660)
>        at
> org.springframework.orm.hibernate3.HibernateAccessor.convertHibernateAccessException(HibernateAccessor.java:412)
>        at
> org.springframework.orm.hibernate3.HibernateTemplate.doExecute(HibernateTemplate.java:411)
>        at
> org.springframework.orm.hibernate3.HibernateTemplate.execute(HibernateTemplate.java:339)
>        at ome.logic.QueryImpl.execute(QueryImpl.java:142)
>        at ome.logic.QueryImpl.findAllByQuery(QueryImpl.java:392)
>        at sun.reflect.GeneratedMethodAccessor293.invoke(Unknown Source)
>        at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>        at java.lang.reflect.Method.invoke(Method.java:597)
>        at
> org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
>        at ome.security.basic.EventHandler.invoke(EventHandler.java:150)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>        at
> org.springframework.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>        at
> org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>        at
> ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:231)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>        at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>        at
> org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
>        at $Proxy72.findAllByQuery(Unknown Source)
>        at sun.reflect.GeneratedMethodAccessor293.invoke(Unknown Source)
>        at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>        at java.lang.reflect.Method.invoke(Method.java:597)
>        at
> org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
>        at
> ome.security.basic.BasicSecurityWiring.invoke(BasicSecurityWiring.java:83)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>        at
> ome.services.blitz.fire.AopContextInitializer.invoke(AopContextInitializer.java:43)
>        at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>        at
> org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
>        at $Proxy72.findAllByQuery(Unknown Source)
>        at sun.reflect.GeneratedMethodAccessor320.invoke(Unknown Source)
>        at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>        at java.lang.reflect.Method.invoke(Method.java:597)
>        at
> ome.services.blitz.util.IceMethodInvoker.invoke(IceMethodInvoker.java:179)
>        at ome.services.throttling.Callback.run(Callback.java:56)
>        at
> ome.services.throttling.InThreadThrottlingStrategy.callInvokerOnRawArgs(InThreadThrottlingStrategy.java:56)
>        at
> ome.services.blitz.impl.AbstractAmdServant.callInvokerOnRawArgs(AbstractAmdServant.java:136)
>        at
> ome.services.blitz.impl.QueryI.findAllByQuery_async(QueryI.java:66)
>        at omero.api._IQueryTie.findAllByQuery_async(_IQueryTie.java:92)
>        at omero.api._IQueryDisp.___findAllByQuery(_IQueryDisp.java:366)
>        at omero.api._IQueryDisp.__dispatch(_IQueryDisp.java:496)
>        at IceInternal.Incoming.invoke(Incoming.java:159)
>        at Ice.ConnectionI.invokeAll(ConnectionI.java:2037)
>        at Ice.ConnectionI.message(ConnectionI.java:972)
>        at IceInternal.ThreadPool.run(ThreadPool.java:577)
>        at IceInternal.ThreadPool.access$100(ThreadPool.java:12)
>        at
> IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:971)
>
>    serverExceptionClass = ome.conditions.ApiUsageException
>    message = unexpected end of subtree [select m from
> ome.model.core.PixelsOriginalFileMap as m join fetch m.parent where
> m.child.id in ()]; nested exception is
> org.hibernate.hql.ast.QuerySyntaxException: unexpected end of subtree
> [select m from ome.model.core.PixelsOriginalFileMap as m join fetch m.parent
> where m.child.id in ()]
> }
>
>
> <WSGIRequest
> GET:<QueryDict: {}>,
> POST:<QueryDict: {}>,
> COOKIES:{'__utma':
> '192312886.126904984.1289803522.1316045304.1316486455.52',
>  '__utmz': '192312886.1316486455.52.35.utmcsr=monash.edu
> |utmccn=(referral)|utmcmd=referral|utmcct=/',
>  'sessionid': '5fd671309cdddd3b278bf04c5d2f0580'},
> META:{'CONTENT_TYPE': 'application/json; charset=utf-8',
>  'DOCUMENT_ROOT': '/srv/webstart',
>  'GATEWAY_INTERFACE': 'CGI/1.1',
>  'HTTPS': 'on',
>  'HTTP_ACCEPT': 'application/json, text/javascript, */*',
>  'HTTP_ACCEPT_CHARSET': 'ISO-8859-1,utf-8;q=0.7,*;q=0.3',
>  'HTTP_ACCEPT_ENCODING': 'gzip,deflate,sdch',
>  'HTTP_ACCEPT_LANGUAGE': 'en-US,en;q=0.8',
>  'HTTP_CONNECTION': 'keep-alive',
>  'HTTP_COOKIE':
> '__utma=203357902.1484366806.1315973805.1315973805.1316391535.2;
> __utmz=203357902.1315973805.1.1.utmcsr=(direct)|utmccn=(direct)|utmcmd=(none);
> __utma=192312886.126904984.1289803522.1316045304.1316486455.52;
> __utmz=192312886.1316486455.52.35.utmcsr=monash.edu|utmccn=(referral)|utmcmd=referral|utmcct=/;
> sessionid=5fd671309cdddd3b278bf04c5d2f0580',
>  'HTTP_HOST': 'server.its.monash.edu.au <http://vera145.its.monash.edu.au>
> ',
>  'HTTP_REFERER': 'https://server.its.monash.edu.au/webadmin/drivespace/<https://vera145.its.monash.edu.au/webadmin/drivespace/>
> ',
>  'HTTP_USER_AGENT': 'Mozilla/5.0 (Windows NT 5.1) AppleWebKit/535.1 (KHTML,
> like Gecko) Chrome/14.0.835.163 Safari/535.1',
>  'HTTP_X_REQUESTED_WITH': 'XMLHttpRequest',
>  'PATH': '/sbin:/usr/sbin:/bin:/usr/bin',
>  'PATH_INFO': u'/webadmin/load_drivespace/',
>  'PATH_TRANSLATED':
> '/srv/omeroserver/var/omero.fcgi/webadmin/load_drivespace/',
>  'QUERY_STRING': '',
>  'REMOTE_ADDR': '110.174.176.101',
>  'REMOTE_PORT': '4153',
>  'REQUEST_METHOD': 'GET',
>  'REQUEST_URI': '/webadmin/load_drivespace/',
>  'SCRIPT_FILENAME': '/srv/omeroserver/var/omero.fcgi',
>  'SCRIPT_NAME': u'',
>  'SERVER_ADDR': '130.194.245.190',
>  'SERVER_ADMIN': 'root at localhost',
>  'SERVER_NAME': 'server.its.monash.edu.au<http://vera145.its.monash.edu.au>
> ',
>  'SERVER_PORT': '443',
>  'SERVER_PROTOCOL': 'HTTP/1.1',
>  'SERVER_SIGNATURE': '<address>Apache/2.2.3 (Red Hat) Server at
> server.its.monash.edu.au <http://vera145.its.monash.edu.au> Port
> 443</address>\n',
>  'SERVER_SOFTWARE': 'Apache/2.2.3 (Red Hat)',
>  'wsgi.errors': <flup.server.fcgi_base.TeeOutputStream object at
> 0x10e30ed0>,
>  'wsgi.input': <flup.server.fcgi_base.InputStream object at 0x10c98410>,
>  'wsgi.multiprocess': True,
>  'wsgi.multithread': False,
>  'wsgi.run_once': False,
>  'wsgi.url_scheme': 'https',
>  'wsgi.version': (1, 0)}>
>
>
> Any idea what the problem is and how to resolve it?
>
> P.S.:
> This worked perfectly in 4.2.2.
>
> Cheers,
> Leon
> _______________________________________________
> ome-users mailing list
> ome-users at lists.openmicroscopy.org.uk
> http://lists.openmicroscopy.org.uk/mailman/listinfo/ome-users
>
>
>   William Moore
> Wellcome Trust Centre for Gene Regulation & Expression
> College of Life Sciences
> MSI/WTB/JBC Complex
> University of Dundee
> Dow Street
> Dundee  DD1 5EH
> United Kingdom
>
> Phone 01382 386364
> http://openmicroscopy.org
>
>
>
>
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.openmicroscopy.org.uk/pipermail/ome-users/attachments/20110921/9860d254/attachment.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: 2011-09-21_223037.jpg
Type: image/jpeg
Size: 71640 bytes
Desc: not available
URL: <http://lists.openmicroscopy.org.uk/pipermail/ome-users/attachments/20110921/9860d254/attachment.jpg>


More information about the ome-users mailing list