Page 1 of 2

Using scripts in OMERO.insight

PostPosted: Sun Sep 05, 2010 4:26 pm
by hulet
We are trying to use the Combine images util script in OMERO.insight. We authenticate to our OMERO server just fine. When we try to run any script it gives us the error:

Cannot load the script: 10
Please contact your administrator.

I've checked the server and the scripts are there. I'm assuming I have to configure something on the server to allow OMERO.insight client access to the scripts. Any help, hints, or links would be appreciated.

Re: Using scripts in OMERO.insight

PostPosted: Sun Sep 05, 2010 6:46 pm
by jmoore
Could you send us the contents of the Blitz-0.log and Processor-0.log from the var/log directory on the server, and possibly the omeroinsight.log from ~/omero/log on the machine where you started Insight?

Thanks,
~Josh

Re: Using scripts in OMERO.insight

PostPosted: Mon Sep 06, 2010 5:09 pm
by hulet
I tried to Upload attachment but it said the extension is not allowed (I tried .log, .txt, and nothing). So I'm pasting in the log files but I ran into a 60,000 character limit so I'll make two posts. omeroinight.log and Processor-0.log are included in this post.

omeroinsight.log:

0 ms since start (2010-09-06 11:44:11,642) ** INFO **
[thread: Initializer][class: org.openmicroscopy.shoola.env.rnd.PixelsServicesFactory] -
Heap memory usage: max 518979584

46 ms since start (2010-09-06 11:44:11,688) ** INFO **
[thread: Initializer][class: org.openmicroscopy.shoola.env.init.TaskBarInit] -
Loaded L&F: com.sun.java.swing.plaf.windows.WindowsLookAndFeel

11390 ms since start (2010-09-06 11:44:23,032) ** INFO **
[thread: Initializer][class: org.openmicroscopy.shoola.env.data.login.LoginServiceImpl] -
Logged onto OMERO at: ome.bioinfo.ittc.ku.edu
User Name: secret -- Password: ********


37469 ms since start (2010-09-06 11:44:49,111) ** ERROR **
[thread: AWT-EventQueue-0][class: org.openmicroscopy.shoola.agents.metadata.ScriptLoader] -
Data Retrieval Failure: org.openmicroscopy.shoola.env.data.ScriptingException: Cannot load the script: 10
at org.openmicroscopy.shoola.env.data.OMEROGateway.loadScript(OMEROGateway.java:5587)
at org.openmicroscopy.shoola.env.data.OmeroImageServiceImpl.loadScript(OmeroImageServiceImpl.java:935)
at org.openmicroscopy.shoola.env.data.views.calls.ScriptsLoader$2.doCall(ScriptsLoader.java:97)
at org.openmicroscopy.shoola.env.data.views.BatchCall.doStep(BatchCall.java:145)
at org.openmicroscopy.shoola.util.concur.tasks.CompositeTask.doStep(CompositeTask.java:226)
at org.openmicroscopy.shoola.env.data.views.CompositeBatchCall.doStep(CompositeBatchCall.java:126)
at org.openmicroscopy.shoola.util.concur.tasks.ExecCommand.exec(ExecCommand.java:165)
at org.openmicroscopy.shoola.util.concur.tasks.ExecCommand.run(ExecCommand.java:274)
at org.openmicroscopy.shoola.util.concur.tasks.AsyncProcessor$Runner.run(AsyncProcessor.java:91)
at java.lang.Thread.run(Unknown Source)
Caused by: omero.ValidationException
serverStackTrace = ""
serverExceptionClass = ""
message = "Can't find params for 10. Stderr is in file 301

---stderr---
File "./script", line 526
finally:
^
SyntaxError: invalid syntax
"
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source)
at java.lang.reflect.Constructor.newInstance(Unknown Source)
at java.lang.Class.newInstance0(Unknown Source)
at java.lang.Class.newInstance(Unknown Source)
at IceInternal.BasicStream$DynamicUserExceptionFactory.createAndThrow(BasicStream.java:2243)
at IceInternal.BasicStream.throwException(BasicStream.java:1632)
at IceInternal.Outgoing.throwUserException(Outgoing.java:442)
at omero.api._IScriptDelM.getParams(_IScriptDelM.java:191)
at omero.api.IScriptPrxHelper.getParams(IScriptPrxHelper.java:254)
at omero.api.IScriptPrxHelper.getParams(IScriptPrxHelper.java:226)
at org.openmicroscopy.shoola.env.data.OMEROGateway.loadScript(OMEROGateway.java:5585)
... 9 more
Data Retrieval Failure: org.openmicroscopy.shoola.env.data.ScriptingException: Cannot load the script: 10
at org.openmicroscopy.shoola.env.data.OMEROGateway.loadScript(OMEROGateway.java:5587)
at org.openmicroscopy.shoola.env.data.OmeroImageServiceImpl.loadScript(OmeroImageServiceImpl.java:935)
at org.openmicroscopy.shoola.env.data.views.calls.ScriptsLoader$2.doCall(ScriptsLoader.java:97)
at org.openmicroscopy.shoola.env.data.views.BatchCall.doStep(BatchCall.java:145)
at org.openmicroscopy.shoola.util.concur.tasks.CompositeTask.doStep(CompositeTask.java:226)
at org.openmicroscopy.shoola.env.data.views.CompositeBatchCall.doStep(CompositeBatchCall.java:126)
at org.openmicroscopy.shoola.util.concur.tasks.ExecCommand.exec(ExecCommand.java:165)
at org.openmicroscopy.shoola.util.concur.tasks.ExecCommand.run(ExecCommand.java:274)
at org.openmicroscopy.shoola.util.concur.tasks.AsyncProcessor$Runner.run(AsyncProcessor.java:91)
at java.lang.Thread.run(Unknown Source)
Caused by: omero.ValidationException
serverStackTrace = ""
serverExceptionClass = ""
message = "Can't find params for 10. Stderr is in file 301

---stderr---
File "./script", line 526
finally:
^
SyntaxError: invalid syntax
"
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source)
at java.lang.reflect.Constructor.newInstance(Unknown Source)
at java.lang.Class.newInstance0(Unknown Source)
at java.lang.Class.newInstance(Unknown Source)
at IceInternal.BasicStream$DynamicUserExceptionFactory.createAndThrow(BasicStream.java:2243)
at IceInternal.BasicStream.throwException(BasicStream.java:1632)
at IceInternal.Outgoing.throwUserException(Outgoing.java:442)
at omero.api._IScriptDelM.getParams(_IScriptDelM.java:191)
at omero.api.IScriptPrxHelper.getParams(IScriptPrxHelper.java:254)
at omero.api.IScriptPrxHelper.getParams(IScriptPrxHelper.java:226)
at org.openmicroscopy.shoola.env.data.OMEROGateway.loadScript(OMEROGateway.java:5585)
... 9 more
Exception in thread "AWT-EventQueue-0"

Processor-0.log:

2010-09-06 06:26:20,619 INFO [ omero.util.Server] (MainThread) ********************************************************************************
2010-09-06 06:26:20,620 INFO [ omero.util.Server] (MainThread) Starting
2010-09-06 06:26:20,621 INFO [ omero.util.Resources] (Thread-2 ) Starting
2010-09-06 06:26:30,779 INFO [ omero.utils] (MainThread) Failed to get session on attempt 1
2010-09-06 06:26:40,882 INFO [ omero.remote] (Dummy-3 ) Meth: ProcessorI.requestRunning
2010-09-06 06:26:40,882 INFO [ omero.remote] (Dummy-3 ) Rslt: None
2010-09-06 06:26:40,885 INFO [ omero.processor.ProcessorI] (MainThread) Registering processor Processor -t @ Processor-0.ProcessorAdapter
2010-09-06 06:26:40,910 INFO [ omero.util.Server] (MainThread) Entering main loop
2010-09-06 06:31:25,001 INFO [ omero.remote] (Dummy-4 ) Meth: ProcessorI.willAccept
2010-09-06 06:31:25,073 INFO [ omero.remote] (Dummy-4 ) Rslt: True
2010-09-06 06:31:25,206 INFO [ omero.remote] (Dummy-5 ) Meth: ProcessorI.parseJob
2010-09-06 06:31:25,206 INFO [ omero.processor.ProcessorI] (Dummy-5 ) parseJob: Session = 9fd521c1-dd02-4ab4-b5b4-c2859f3f399b, JobId = 101
2010-09-06 06:31:25,273 INFO [ omero.processor.ProcessI] (Dummy-5 ) Created 9fd521c1-dd02-4ab4-b5b4-c2859f3f399b in /tmp/omero/tmp/omero_apache/19209/processYnoSQb.dir
2010-09-06 06:31:25,285 INFO [ omero.processor.ProcessorI] (Dummy-5 ) Downloaded file: 10
2010-09-06 06:31:25,290 INFO [ omero.processor.ProcessI] (Dummy-5 ) <proc:19435,rc=-,uuid=9fd521c1-dd02-4ab4-b5b4-c2859f3f399b> : Activated
2010-09-06 06:31:25,322 INFO [ omero.remote] (Dummy-5 ) Meth: ProcessI.wait
2010-09-06 06:31:25,322 INFO [ omero.processor.ProcessI] (Dummy-5 ) <proc:19435,rc=1,uuid=9fd521c1-dd02-4ab4-b5b4-c2859f3f399b> : Waiting
2010-09-06 06:31:25,322 INFO [ omero.processor.ProcessI] (Dummy-5 ) <proc:19435,rc=1,uuid=9fd521c1-dd02-4ab4-b5b4-c2859f3f399b> : Deactivating
2010-09-06 06:31:25,322 INFO [ omero.remote] (Dummy-5 ) Meth: ProcessI.shutdown
2010-09-06 06:31:25,323 INFO [ omero.remote] (Dummy-5 ) Rslt: None
2010-09-06 06:31:25,390 INFO [ omero.processor.ProcessI] (Dummy-5 ) <proc:19435,rc=1,uuid=9fd521c1-dd02-4ab4-b5b4-c2859f3f399b> : Changed job status from Running to Error
2010-09-06 06:31:25,393 INFO [ omero.processor.ProcessI] (Dummy-5 ) <proc:19435,rc=1,uuid=9fd521c1-dd02-4ab4-b5b4-c2859f3f399b> : No stdout
2010-09-06 06:31:25,537 INFO [ omero.processor.ProcessI] (Dummy-5 ) <proc:19435,rc=1,uuid=9fd521c1-dd02-4ab4-b5b4-c2859f3f399b> : Uploaded 81 bytes of /tmp/omero/tmp/omero_apache/19209/processYnoSQb.dir/err to 301
2010-09-06 06:31:25,539 INFO [ omero.processor.ProcessI] (Dummy-5 ) <proc:19435,rc=1,uuid=9fd521c1-dd02-4ab4-b5b4-c2859f3f399b> : Lived 0s. Deactivation took 0s.
2010-09-06 06:31:25,539 INFO [ omero.processor.ProcessI] (Dummy-5 ) <proc:19435,rc=1,uuid=9fd521c1-dd02-4ab4-b5b4-c2859f3f399b> : Callback processFinished
2010-09-06 06:31:25,540 INFO [ omero.remote] (Dummy-5 ) Rslt: 1
2010-09-06 06:31:25,571 WARNI [ omero.processor.ProcessorI] (Dummy-5 ) No output found for omero.scripts.parse. Keys: []
2010-09-06 06:31:25,572 INFO [ omero.remote] (Dummy-5 ) Rslt: None
2010-09-06 06:32:20,627 INFO [ omero.remote] (Thread-2 ) Meth: ProcessI.poll
2010-09-06 06:32:20,628 INFO [ omero.remote] (Thread-2 ) Rslt: object #0 (::omero::RInt)
{
_val = 1
}
2010-09-06 06:33:20,638 INFO [ omero.remote] (Thread-2 ) Meth: ProcessI.poll
2010-09-06 06:33:20,639 INFO [ omero.remote] (Thread-2 ) Rslt: object #0 (::omero::RInt)
{
_val = 1
}
2010-09-06 06:34:20,648 INFO [ omero.remote] (Thread-2 ) Meth: ProcessI.poll
2010-09-06 06:34:20,649 INFO [ omero.remote] (Thread-2 ) Rslt: object #0 (::omero::RInt)
{
_val = 1
}

Re: Using scripts in OMERO.insight

PostPosted: Mon Sep 06, 2010 5:20 pm
by hulet
I hit the character limit on the Blitz-0.log so I am including the first 50,000 characters. Let me know if you need the rest of the Blitz-0.log.

Blitz-0.log:

2010-09-06 06:26:20,303 INFO [ ome.services.blitz.Entry] ( main) Creating OMERO.blitz. Please wait...
2010-09-06 06:26:21,391 INFO [ng.ShutdownSafeEhcacheManagerFactoryBean] ( main) Initializing EHCache CacheManager
2010-09-06 06:26:23,825 INFO [ ome.services.fulltext.FullTextAnalyzer] ( main) Initialized FullTextAnalyzer
2010-09-06 06:26:23,877 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:23,891 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:23,899 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:23,925 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:23,926 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:23,928 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:23,929 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:23,937 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:23,955 WARN [ch.engine.DocumentBuilderContainedEntity] ( main) @DocumentId specified on an entity which is not indexed by itself. Annotation gets ignored. Use @Field instead.
2010-09-06 06:26:26,474 INFO [ ome.services.db.DatabaseIdentity] ( main) Using LSID format: urn:lsid:export.openmicroscopy.org:%s:04483961-7b32-458b-974b-3103a4c6ff27_%s%s
2010-09-06 06:26:26,537 INFO [ ome.services.fulltext.FullTextThread] ( main) Initializing Full-Text Indexer
2010-09-06 06:26:26,555 INFO [ ome.services.util.DBPatchCheck] ( main) Verified database patch: OMERO4.2__0
2010-09-06 06:26:26,821 INFO [ ome.services.util.ServerVersionCheck] ( main) -------------------------------------------------
2010-09-06 06:26:26,821 INFO [ ome.services.util.ServerVersionCheck] ( main) OMERO Version: Beta-4.2.0-r7571-b29 Ready.
2010-09-06 06:26:26,821 INFO [ ome.services.util.ServerVersionCheck] ( main) -------------------------------------------------
2010-09-06 06:26:30,033 INFO [ ome.system.UpgradeCheck] ( main) no update needed
2010-09-06 06:26:30,043 INFO [ ome.tools.hibernate.ExtendedMetadata] ( main) Calculating ExtendedMetadata...
2010-09-06 06:26:30,097 INFO [.services.scheduler.SchedulerFactoryBean] ( main) Starting Quartz Scheduler now
2010-09-06 06:26:30,574 INFO [e.services.blitz.util.BlitzConfiguration] ( main) Initializing Ice.Communicator
2010-09-06 06:26:30,763 INFO [ ome.services.blitz.fire.Ring] ( main) Checking cluster
2010-09-06 06:26:30,780 INFO [ ome.services.blitz.fire.Registry] ( main) Found 0 cluster node(s) : []
2010-09-06 06:26:30,780 INFO [ ome.services.blitz.fire.Ring] ( main) Got 0 cluster uuids : []
2010-09-06 06:26:30,786 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.getManagerList
2010-09-06 06:26:30,786 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@34287ca7]
2010-09-06 06:26:30,820 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=null(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:30,863 INFO [ org.perf4j.TimingLogger] ( main) start[1283772390786] time[77] tag[omero.call.success]
2010-09-06 06:26:30,863 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: (1243df21-94d9-4453-9c4a-5b5c5b9e02c9)
2010-09-06 06:26:30,864 INFO [ ome.services.blitz.fire.Ring] ( main) Purging node: 1243df21-94d9-4453-9c4a-5b5c5b9e02c9
2010-09-06 06:26:30,893 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.executeUpdate - set closed = now()
2010-09-06 06:26:30,893 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@1f92ee25]
2010-09-06 06:26:30,966 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=5121(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:30,992 INFO [ org.perf4j.TimingLogger] ( main) start[1283772390893] time[98] tag[omero.call.success]
2010-09-06 06:26:30,992 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: 0
2010-09-06 06:26:30,992 INFO [ ome.services.blitz.fire.Ring] ( main) Removed 0 entries with value 1243df21-94d9-4453-9c4a-5b5c5b9e02c9
2010-09-06 06:26:30,992 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.setManagerDown
2010-09-06 06:26:30,992 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@69b8b810]
2010-09-06 06:26:31,007 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=5122(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:31,015 INFO [ org.perf4j.TimingLogger] ( main) start[1283772390992] time[23] tag[omero.call.success]
2010-09-06 06:26:31,015 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: 1
2010-09-06 06:26:31,015 INFO [ ome.services.blitz.fire.Ring] ( main) Removed manager: 1243df21-94d9-4453-9c4a-5b5c5b9e02c9
2010-09-06 06:26:31,015 INFO [ ome.services.blitz.fire.Ring] ( main) handleRingShutdown: 1243df21-94d9-4453-9c4a-5b5c5b9e02c9
2010-09-06 06:26:31,016 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.addManager
2010-09-06 06:26:31,016 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@4afbf04a]
2010-09-06 06:26:31,027 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=5123(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:31,032 INFO [ ome.security.basic.CurrentDetails] ( main) Adding log:INSERT,class ome.model.meta.Node,101
2010-09-06 06:26:31,039 INFO [ org.perf4j.TimingLogger] ( main) start[1283772391016] time[23] tag[omero.call.success]
2010-09-06 06:26:31,040 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: ome.model.meta.Node:Id_101
2010-09-06 06:26:31,043 INFO [ ome.services.blitz.fire.Registry] ( main) Added ClusterNode/0ddb8d3c-3464-40bd-93a0-23c0add6aa52 to registry
2010-09-06 06:26:31,081 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.scripts.ScriptRepoHelper.loadAll(true)
2010-09-06 06:26:31,081 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@2d523d40]
2010-09-06 06:26:31,095 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=5124(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:31,203 INFO [ org.perf4j.TimingLogger] ( main) start[1283772391081] time[122] tag[omero.call.success]
2010-09-06 06:26:31,203 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: (ome.model.core.OriginalFile:Id_1, ome.model.core.OriginalFile:Id_2, ome.model.core.OriginalFile:Id_3, ... 8 more)
2010-09-06 06:26:31,223 INFO [.services.blitz.repo.AbstractRepositoryI] ( main) Initializing repository in /bio/rgs/mai/OMERO
2010-09-06 06:26:31,229 INFO [.services.blitz.repo.AbstractRepositoryI] ( main) Initializing repository in /bio/rgs/mai/OMERO.server-Beta-4.2.0/./lib/scripts
2010-09-06 06:26:31,258 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Executor.doWork -- ome.services.roi.GeomTool.backgroundSynchronization
2010-09-06 06:26:31,260 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Args: [null, ome.tools.spring.InternalServiceFactory@6c696d5c]
2010-09-06 06:26:31,260 INFO [ ome.services.util.ServiceHandler] (r_Worker-6) Executor.doWork -- ome.services.blitz.repo.ScriptRepositoryI.takeover
2010-09-06 06:26:31,260 INFO [ ome.services.util.ServiceHandler] (r_Worker-6) Args: [null, ome.tools.spring.InternalServiceFactory@2b316b76]
2010-09-06 06:26:31,261 INFO [ ome.services.util.ServiceHandler] (r_Worker-8) Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.takeover
2010-09-06 06:26:31,261 INFO [ ome.services.util.ServiceHandler] (r_Worker-8) Args: [null, ome.tools.spring.InternalServiceFactory@4382f3da]
2010-09-06 06:26:31,279 INFO [ ome.services.blitz.util.CheckAllJobs] ( Thread-7) Waiting 120 secs. for callbacks
2010-09-06 06:26:31,280 INFO [ ome.services.blitz.Entry] ( main) OMERO.blitz now accepting connections.
2010-09-06 06:26:31,283 INFO [ ome.security.basic.EventHandler] (r_Worker-9) Auth: user=0,group=0,event=5125(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:31,287 INFO [ ome.security.basic.EventHandler] (r_Worker-8) Auth: user=0,group=0,event=5126(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:31,287 INFO [ org.perf4j.TimingLogger] (r_Worker-9) start[1283772391260] time[27] tag[omero.call.success]
2010-09-06 06:26:31,288 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Rslt: null
2010-09-06 06:26:31,289 INFO [ ome.security.basic.EventHandler] (r_Worker-6) Auth: user=0,group=0,event=5127(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:31,297 INFO [.services.blitz.repo.AbstractRepositoryI] (r_Worker-8) Opened repository OMERO (uuid=39a7f9f9-189b-4268-8a2d-9b231597c9a3)
2010-09-06 06:26:31,297 INFO [.services.blitz.repo.AbstractRepositoryI] (r_Worker-6) Opened repository scripts (uuid=ScriptRepo)
2010-09-06 06:26:31,311 INFO [ ome.services.blitz.fire.Registry] (r_Worker-6) Updated InternalRepository-ScriptRepo in registry
2010-09-06 06:26:31,312 INFO [ ome.services.blitz.fire.Registry] (r_Worker-8) Updated InternalRepository-39a7f9f9-189b-4268-8a2d-9b231597c9a3 in registry
2010-09-06 06:26:31,314 INFO [ ome.services.blitz.fire.Registry] (r_Worker-6) Updated PublicRepository-ScriptRepo in registry
2010-09-06 06:26:31,314 INFO [ ome.services.blitz.fire.Registry] (r_Worker-8) Updated PublicRepository-39a7f9f9-189b-4268-8a2d-9b231597c9a3 in registry
2010-09-06 06:26:31,318 INFO [.services.blitz.repo.AbstractRepositoryI] (r_Worker-6) Repository now active
2010-09-06 06:26:31,318 INFO [.services.blitz.repo.AbstractRepositoryI] (r_Worker-8) Repository now active
2010-09-06 06:26:31,322 INFO [ org.perf4j.TimingLogger] (r_Worker-8) start[1283772391261] time[61] tag[omero.call.success]
2010-09-06 06:26:31,322 INFO [ org.perf4j.TimingLogger] (r_Worker-6) start[1283772391260] time[62] tag[omero.call.success]
2010-09-06 06:26:31,322 INFO [ ome.services.util.ServiceHandler] (r_Worker-8) Rslt: ome.model.core.OriginalFile:Id_13
2010-09-06 06:26:31,322 INFO [ ome.services.util.ServiceHandler] (r_Worker-6) Rslt: ome.model.core.OriginalFile:Id_12
2010-09-06 06:26:31,323 INFO [ ome.services.util.ServiceHandler] (r_Worker-8) Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.getDescription(13)
2010-09-06 06:26:31,323 INFO [ ome.services.util.ServiceHandler] (r_Worker-6) Executor.doWork -- ome.services.blitz.repo.ScriptRepositoryI.getDescription(12)
2010-09-06 06:26:31,323 INFO [ ome.services.util.ServiceHandler] (r_Worker-8) Args: [null, ome.tools.spring.InternalServiceFactory@71bb0662]
2010-09-06 06:26:31,323 INFO [ ome.services.util.ServiceHandler] (r_Worker-6) Args: [null, ome.tools.spring.InternalServiceFactory@2da04643]
2010-09-06 06:26:31,324 INFO [ ome.security.basic.EventHandler] (r_Worker-6) Auth: user=0,group=0,event=null(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:31,324 INFO [ ome.security.basic.EventHandler] (r_Worker-8) Auth: user=0,group=0,event=null(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:31,440 INFO [ org.perf4j.TimingLogger] (r_Worker-8) start[1283772391323] time[117] tag[omero.call.success]
2010-09-06 06:26:31,440 INFO [ ome.services.util.ServiceHandler] (r_Worker-8) Rslt: ome.model.core.OriginalFile:Id_13
2010-09-06 06:26:31,440 INFO [ org.perf4j.TimingLogger] (r_Worker-6) start[1283772391323] time[117] tag[omero.call.success]
2010-09-06 06:26:31,440 INFO [ ome.services.util.ServiceHandler] (r_Worker-6) Rslt: ome.model.core.OriginalFile:Id_12
2010-09-06 06:26:40,788 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession
2010-09-06 06:26:40,788 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Args: [null, ome.tools.spring.InternalServiceFactory@44594680]
2010-09-06 06:26:40,802 INFO [ ome.security.basic.EventHandler] (l.Server-7) Auth: user=0,group=0,event=5128(Sessions),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:26:40,838 INFO [ ome.security.basic.CurrentDetails] (l.Server-7) Adding log:INSERT,class ome.model.meta.Session,1308
2010-09-06 06:26:40,873 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772400788] time[85] tag[omero.call.success]
2010-09-06 06:26:40,873 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Rslt: (ome.model.meta.Experimenter:Id_0, ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more)
2010-09-06 06:26:40,876 INFO [ ome.services.licenses.Store] (l.Server-7) Acquired license from example license store.
2010-09-06 06:26:40,878 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-7) Created session 13ffc57a-cc2b-44e6-b25e-1aba82bb3264 for user root
2010-09-06 06:26:40,883 INFO [ ome.services.blitz.util.CheckAllJobs] (l.Server-5) Received 0 job(s)
2010-09-06 06:26:40,885 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-0) Keep alive: 13ffc57a-cc2b-44e6-b25e-1aba82bb3264
2010-09-06 06:26:40,897 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-2) Added servant to adapter: 13ffc57a-cc2b-44e6-b25e-1aba82bb3264/2d52fc50-0a48-4332-b8d5-33f72001a994omero.grid.SharedResources(omero.grid._SharedResourcesTie@108b1a8f)
2010-09-06 06:27:00,012 INFO [ ome.services.blitz.fire.SessionManagerI] (r_Worker-5) Performing requestHeartbeats
2010-09-06 06:27:20,622 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-1) Keep alive: 13ffc57a-cc2b-44e6-b25e-1aba82bb3264
2010-09-06 06:28:20,624 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-8) Keep alive: 13ffc57a-cc2b-44e6-b25e-1aba82bb3264
2010-09-06 06:29:20,625 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-7) Keep alive: 13ffc57a-cc2b-44e6-b25e-1aba82bb3264
2010-09-06 06:30:00,019 INFO [ ome.services.blitz.fire.SessionManagerI] (r_Worker-8) Performing requestHeartbeats
2010-09-06 06:30:13,022 INFO [ ome.services.blitz.fire.Ring] (r_Worker-7) Checking cluster
2010-09-06 06:30:13,025 INFO [ ome.services.blitz.fire.Registry] (r_Worker-7) Found 1 cluster node(s) : [ClusterNode/0ddb8d3c-3464-40bd-93a0-23c0add6aa52 -t:tcp -h 10.10.0.16 -p 36194:tcp -h 129.237.162.12 -p 36194]
2010-09-06 06:30:13,027 INFO [ ome.services.blitz.fire.Ring] (r_Worker-7) Got 1 cluster uuids : [0ddb8d3c-3464-40bd-93a0-23c0add6aa52]
2010-09-06 06:30:13,027 INFO [ ome.services.util.ServiceHandler] (r_Worker-7) Executor.doWork -- ome.services.blitz.fire.Ring.getManagerList
2010-09-06 06:30:13,027 INFO [ ome.services.util.ServiceHandler] (r_Worker-7) Args: [null, ome.tools.spring.InternalServiceFactory@31b8e71]
2010-09-06 06:30:13,029 INFO [ ome.security.basic.EventHandler] (r_Worker-7) Auth: user=0,group=0,event=null(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:30:13,034 INFO [ org.perf4j.TimingLogger] (r_Worker-7) start[1283772613027] time[7] tag[omero.call.success]
2010-09-06 06:30:13,034 INFO [ ome.services.util.ServiceHandler] (r_Worker-7) Rslt: (0ddb8d3c-3464-40bd-93a0-23c0add6aa52)
2010-09-06 06:30:20,626 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-0) Keep alive: 13ffc57a-cc2b-44e6-b25e-1aba82bb3264
2010-09-06 06:30:58,607 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(hulet)
2010-09-06 06:30:58,608 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Args: [null, ome.tools.spring.InternalServiceFactory@f17e440]
2010-09-06 06:30:58,609 INFO [ ome.security.basic.EventHandler] (l.Server-2) Auth: user=0,group=0,event=null(Sessions),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:30:58,615 INFO [ org.perf4j.TimingLogger] (l.Server-2) start[1283772658608] time[7] tag[omero.call.success]
2010-09-06 06:30:58,615 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Rslt: true
2010-09-06 06:30:58,616 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession
2010-09-06 06:30:58,616 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [null, ome.tools.spring.InternalServiceFactory@9482aa9]
2010-09-06 06:30:58,631 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=0,group=0,event=5129(Sessions),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:30:58,654 INFO [ ome.security.basic.CurrentDetails] (l.Server-1) Adding log:INSERT,class ome.model.meta.Session,1309
2010-09-06 06:30:58,680 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1283772658616] time[64] tag[omero.call.success]
2010-09-06 06:30:58,681 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: (ome.model.meta.Experimenter:Id_2, ome.model.meta.ExperimenterGroup:Id_3, [1, 3, 0], ... 4 more)
2010-09-06 06:30:58,681 INFO [ ome.services.licenses.Store] (l.Server-1) Acquired license from example license store.
2010-09-06 06:30:58,687 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-1) Created session 2bf5e9e0-322d-43ee-a72d-45f3b287163b for user hulet
2010-09-06 06:30:58,818 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-6) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/ccce589a-bc99-4d17-80a9-b2c28c31152fomero.api.IConfig(omero.api._IConfigTie@3dd510be)
2010-09-06 06:30:58,841 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Meth: interface ome.api.IConfig.getConfigValue
2010-09-06 06:30:58,841 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Args: [omero.router.insecure]
2010-09-06 06:30:58,854 INFO [ ome.security.basic.EventHandler] (l.Server-8) Auth: user=2,group=3,event=5130(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:30:58,858 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1283772658841] time[17] tag[omero.call.success]
2010-09-06 06:30:58,858 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: OMERO.Glacier2/router:tcp -p 4063 -h @omero.host@
2010-09-06 06:30:58,987 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-9) Found session locally: 2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:30:58,989 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-9) Rejoining session 2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:30:59,021 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-0) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/ccce589a-bc99-4d17-80a9-b2c28c31152fomero.api.IAdmin(omero.api._IAdminTie@50b20090)
2010-09-06 06:30:59,061 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Meth: interface ome.api.IAdmin.lookupExperimenter
2010-09-06 06:30:59,061 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Args: [hulet]
2010-09-06 06:30:59,070 INFO [ ome.security.basic.EventHandler] (l.Server-2) Auth: user=2,group=3,event=5131(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:30:59,077 INFO [ org.perf4j.TimingLogger] (l.Server-2) start[1283772659061] time[16] tag[omero.call.success]
2010-09-06 06:30:59,077 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Rslt: ome.model.meta.Experimenter:Id_2
2010-09-06 06:30:59,411 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.IConfig.getVersion
2010-09-06 06:30:59,411 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: ()
2010-09-06 06:30:59,421 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=2,group=3,event=5132(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:30:59,423 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1283772659411] time[12] tag[omero.call.success]
2010-09-06 06:30:59,423 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: 4.2.0
2010-09-06 06:30:59,439 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Meth: interface ome.api.IAdmin.lookupLdapAuthExperimenter
2010-09-06 06:30:59,439 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Args: [2]
2010-09-06 06:30:59,448 INFO [ ome.security.basic.EventHandler] (l.Server-3) Auth: user=2,group=3,event=5133(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:30:59,450 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1283772659439] time[11] tag[omero.call.success]
2010-09-06 06:30:59,450 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Rslt: null
2010-09-06 06:30:59,461 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-6) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/ccce589a-bc99-4d17-80a9-b2c28c31152fomero.api.IQuery(omero.api._IQueryTie@7ab1a411)
2010-09-06 06:30:59,487 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Meth: interface ome.api.IQuery.findAllByQuery
2010-09-06 06:30:59,487 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Args: [select distinct g from ExperimenterGroup as g join fetch g.groupExperimenterMap as map join fetch map.parent e left outer join fetch map.child u left outer join fetch u.groupExperimenterMap m2 left outer join fetch m2.parent p where g.id in (select m.parent from GroupExperimenterMap m where m.child.id = :id ), ome.parameters.Parameters@1cea01d7]
2010-09-06 06:30:59,488 INFO [ ome.security.basic.EventHandler] (l.Server-8) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:30:59,520 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1283772659487] time[33] tag[omero.call.success]
2010-09-06 06:30:59,520 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: (ome.model.meta.ExperimenterGroup:Id_0, ome.model.meta.ExperimenterGroup:Id_1, ome.model.meta.ExperimenterGroup:Id_3)
2010-09-06 06:31:03,115 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-7) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/ccce589a-bc99-4d17-80a9-b2c28c31152fomero.api.IContainer(omero.api._IContainerTie@a1abcdd)
2010-09-06 06:31:03,235 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Meth: interface ome.api.IContainer.loadContainerHierarchy
2010-09-06 06:31:03,235 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Args: [class ome.model.containers.Project, (), ome.parameters.Parameters@330345ed]
2010-09-06 06:31:03,237 INFO [ ome.security.basic.EventHandler] (l.Server-9) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:03,265 INFO [ org.perf4j.TimingLogger] (l.Server-9) start[1283772663235] time[30] tag[omero.call.success]
2010-09-06 06:31:03,265 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Rslt: (ome.model.containers.Project:Id_3)
2010-09-06 06:31:03,481 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Meth: interface ome.api.IContainer.getCollectionCount
2010-09-06 06:31:03,481 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Args: [ome.model.containers.Dataset, ome.model.containers.Dataset_imageLinks, (6), null]
2010-09-06 06:31:03,483 INFO [ ome.security.basic.EventHandler] (l.Server-5) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:03,490 INFO [ org.perf4j.TimingLogger] (l.Server-5) start[1283772663481] time[9] tag[omero.call.success]
2010-09-06 06:31:03,490 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Rslt: {6=0}
2010-09-06 06:31:09,871 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Meth: interface ome.api.IContainer.loadContainerHierarchy
2010-09-06 06:31:09,871 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Args: [class ome.model.containers.Project, (), ome.parameters.Parameters@6ca79a6a]
2010-09-06 06:31:09,873 INFO [ ome.security.basic.EventHandler] (l.Server-0) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:09,897 INFO [ org.perf4j.TimingLogger] (l.Server-0) start[1283772669871] time[26] tag[omero.call.success]
2010-09-06 06:31:09,898 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Rslt: (ome.model.containers.Project:Id_2, ome.model.containers.Project:Id_4)
2010-09-06 06:31:10,017 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Meth: interface ome.api.IContainer.getCollectionCount
2010-09-06 06:31:10,017 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Args: [ome.model.containers.Dataset, ome.model.containers.Dataset_imageLinks, (3, 4, 5, ... 3 more), null]
2010-09-06 06:31:10,018 INFO [ ome.security.basic.EventHandler] (l.Server-2) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:10,027 INFO [ org.perf4j.TimingLogger] (l.Server-2) start[1283772670017] time[10] tag[omero.call.success]
2010-09-06 06:31:10,027 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Rslt: {3=3, 4=3, 5=4, ... 3 more}
2010-09-06 06:31:12,084 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-4) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/ccce589a-bc99-4d17-80a9-b2c28c31152fomero.api.IMetadata(omero.api._IMetadataTie@1dd88d65)
2010-09-06 06:31:12,146 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.IMetadata.loadAnnotations
2010-09-06 06:31:12,147 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [class ome.model.containers.Project, (2), (), (), ome.parameters.Parameters@53a816e5]
2010-09-06 06:31:12,148 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:12,156 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1283772672147] time[9] tag[omero.call.success]
2010-09-06 06:31:12,156 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: {2=()}
2010-09-06 06:31:13,732 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Meth: interface ome.api.IMetadata.loadAnnotations
2010-09-06 06:31:13,732 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Args: [class ome.model.containers.Dataset, (5), (), (), ome.parameters.Parameters@7c60f77e]
2010-09-06 06:31:13,734 INFO [ ome.security.basic.EventHandler] (l.Server-3) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:13,739 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1283772673732] time[7] tag[omero.call.success]
2010-09-06 06:31:13,739 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Rslt: {5=()}
2010-09-06 06:31:13,763 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Meth: interface ome.api.IContainer.loadContainerHierarchy
2010-09-06 06:31:13,763 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Args: [class ome.model.containers.Dataset, (5), ome.parameters.Parameters@42a42fed]
2010-09-06 06:31:13,764 INFO [ ome.security.basic.EventHandler] (l.Server-6) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:13,803 INFO [ org.perf4j.TimingLogger] (l.Server-6) start[1283772673763] time[40] tag[omero.call.success]
2010-09-06 06:31:13,804 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Rslt: (ome.model.containers.Dataset:Id_5)
2010-09-06 06:31:14,750 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-8) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/81:ed:a2:c:-14d88ac2:12ae6cbf8f9:-7ffbomero.api.ThumbnailStore(omero.api._ThumbnailStoreTie@2d13b417)
2010-09-06 06:31:14,837 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Meth: interface ome.api.ThumbnailStore.getThumbnailByLongestSideSet
2010-09-06 06:31:14,837 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Args: [96, (200, 197, 199, ... 1 more)]
2010-09-06 06:31:14,848 INFO [ ome.security.basic.EventHandler] (l.Server-7) Auth: user=2,group=3,event=5134(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:14,879 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772674857] time[22] tag[omero.bulkLoadRenderingSettings]
2010-09-06 06:31:14,880 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772674880] time[0] tag[omero.createAndPrepareMissingRenderingSettings]
2010-09-06 06:31:14,897 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772674880] time[17] tag[omero.bulkLoadMetadata]
2010-09-06 06:31:14,897 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772674880] time[17] tag[omero.loadMetadataByDimensionPool]
2010-09-06 06:31:14,897 INFO [ ome.services.ThumbnailCtx] (l.Server-7) New thumbnail object set size: 0
2010-09-06 06:31:14,897 INFO [ ome.services.ThumbnailCtx] (l.Server-7) Dimension pool size: 0
2010-09-06 06:31:14,897 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772674897] time[0] tag[omero.createMissingThumbnailMetadata]
2010-09-06 06:31:14,907 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772674837] time[70] tag[omero.call.success]
2010-09-06 06:31:14,907 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Rslt: {200=[-1, -40, -1, ... 1 more], 197=[-1, -40, -1, ... 1 more], 199=[-1, -40, -1, ... 1 more], ... 1 more}
2010-09-06 06:31:15,723 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Meth: interface ome.api.IMetadata.loadAnnotations
2010-09-06 06:31:15,723 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Args: [class ome.model.core.Image, (197), (), (), ome.parameters.Parameters@20b8db77]
2010-09-06 06:31:15,724 INFO [ ome.security.basic.EventHandler] (l.Server-9) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:15,740 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-5) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/6ccf9411-76c3-4be1-aaf7-5cd9904c40daomero.api.IPixels(omero.api._IPixelsTie@1247de8f)
2010-09-06 06:31:15,742 INFO [ org.perf4j.TimingLogger] (l.Server-9) start[1283772675723] time[19] tag[omero.call.success]
2010-09-06 06:31:15,742 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Rslt: {197=(ome.model.annotations.FileAnnotation:Id_197)}
2010-09-06 06:31:15,826 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:15,827 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Args: [class ome.model.enums.Immersion]
2010-09-06 06:31:15,828 INFO [ ome.security.basic.EventHandler] (l.Server-2) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:15,831 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:15,831 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [class ome.model.enums.Illumination]
2010-09-06 06:31:15,837 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:15,837 INFO [ org.perf4j.TimingLogger] (l.Server-2) start[1283772675827] time[10] tag[omero.call.success]
2010-09-06 06:31:15,838 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Rslt: (ome.model.enums.Immersion:Id_1, ome.model.enums.Immersion:Id_2, ome.model.enums.Immersion:Id_3, ... 5 more)
2010-09-06 06:31:15,841 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1283772675831] time[10] tag[omero.call.success]
2010-09-06 06:31:15,841 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: (ome.model.enums.Illumination:Id_1, ome.model.enums.Illumination:Id_2, ome.model.enums.Illumination:Id_3, ... 3 more)
2010-09-06 06:31:15,917 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:15,917 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [class ome.model.enums.Correction]
2010-09-06 06:31:15,918 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:15,922 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Meth: interface ome.api.IMetadata.loadAnnotations
2010-09-06 06:31:15,922 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Args: [class ome.model.core.Image, (197), (), (), ome.parameters.Parameters@53a6f572]
2010-09-06 06:31:15,923 INFO [ ome.security.basic.EventHandler] (l.Server-3) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:15,923 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1283772675917] time[6] tag[omero.call.success]
2010-09-06 06:31:15,923 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: (ome.model.enums.Correction:Id_1, ome.model.enums.Correction:Id_2, ome.model.enums.Correction:Id_3, ... 12 more)
2010-09-06 06:31:15,936 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1283772675922] time[14] tag[omero.call.success]
2010-09-06 06:31:15,936 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Rslt: {197=(ome.model.annotations.FileAnnotation:Id_197)}
2010-09-06 06:31:15,939 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:15,939 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Args: [class ome.model.enums.Medium]
2010-09-06 06:31:15,940 INFO [ ome.security.basic.EventHandler] (l.Server-6) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:15,943 INFO [ org.perf4j.TimingLogger] (l.Server-6) start[1283772675939] time[4] tag[omero.call.success]
2010-09-06 06:31:15,943 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Rslt: (ome.model.enums.Medium:Id_1, ome.model.enums.Medium:Id_2, ome.model.enums.Medium:Id_3, ... 3 more)
2010-09-06 06:31:15,953 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:15,953 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Args: [class ome.model.enums.ContrastMethod]
2010-09-06 06:31:15,954 INFO [ ome.security.basic.EventHandler] (l.Server-8) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:15,959 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1283772675953] time[6] tag[omero.call.success]
2010-09-06 06:31:15,959 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: (ome.model.enums.ContrastMethod:Id_1, ome.model.enums.ContrastMethod:Id_2, ome.model.enums.ContrastMethod:Id_3, ... 7 more)
2010-09-06 06:31:16,013 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,013 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Args: [class ome.model.enums.AcquisitionMode]
2010-09-06 06:31:16,014 INFO [ ome.security.basic.EventHandler] (l.Server-7) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,022 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772676013] time[9] tag[omero.call.success]
2010-09-06 06:31:16,022 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Rslt: (ome.model.enums.AcquisitionMode:Id_1, ome.model.enums.AcquisitionMode:Id_2, ome.model.enums.AcquisitionMode:Id_3, ... 18 more)
2010-09-06 06:31:16,090 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,090 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Args: [class ome.model.enums.Format]
2010-09-06 06:31:16,091 INFO [ ome.security.basic.EventHandler] (l.Server-5) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,130 INFO [ org.perf4j.TimingLogger] (l.Server-5) start[1283772676090] time[40] tag[omero.call.success]
2010-09-06 06:31:16,131 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Rslt: (ome.model.enums.Format:Id_1, ome.model.enums.Format:Id_2, ome.model.enums.Format:Id_3, ... 164 more)
2010-09-06 06:31:16,221 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Meth: interface ome.api.IQuery.findAllByQuery
2010-09-06 06:31:16,221 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Meth: interface ome.api.IQuery.findAllByQuery
2010-09-06 06:31:16,221 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Args: [select link from ImageAnnotationLink as link left outer join fetch link.child child left outer join fetch child.details.owner left outer join fetch link.details.owner where link.child.id in (:childIDs) and link.parent.id = :parentID, ome.parameters.Parameters@43e7a657]
2010-09-06 06:31:16,221 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Args: [select link from ImageAnnotationLink as link left outer join fetch link.child child left outer join fetch child.details.owner left outer join fetch link.details.owner where link.child.id in (:childIDs) and link.parent.id = :parentID, ome.parameters.Parameters@1d59bc95]
2010-09-06 06:31:16,223 INFO [ ome.security.basic.EventHandler] (l.Server-9) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,223 INFO [ ome.security.basic.EventHandler] (l.Server-0) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,241 INFO [ org.perf4j.TimingLogger] (l.Server-0) start[1283772676221] time[20] tag[omero.call.success]
2010-09-06 06:31:16,241 INFO [ org.perf4j.TimingLogger] (l.Server-9) start[1283772676221] time[20] tag[omero.call.success]
2010-09-06 06:31:16,241 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Rslt: (ome.model.annotations.ImageAnnotationLink:Id_197)
2010-09-06 06:31:16,241 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Rslt: (ome.model.annotations.ImageAnnotationLink:Id_197)
2010-09-06 06:31:16,256 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,256 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Args: [class ome.model.enums.Binning]
2010-09-06 06:31:16,257 INFO [ ome.security.basic.EventHandler] (l.Server-2) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,260 INFO [ org.perf4j.TimingLogger] (l.Server-2) start[1283772676256] time[4] tag[omero.call.success]
2010-09-06 06:31:16,260 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Rslt: (ome.model.enums.Binning:Id_1, ome.model.enums.Binning:Id_2, ome.model.enums.Binning:Id_3, ... 1 more)
2010-09-06 06:31:16,329 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,329 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [class ome.model.enums.DetectorType]
2010-09-06 06:31:16,330 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,336 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1283772676329] time[7] tag[omero.call.success]
2010-09-06 06:31:16,336 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: (ome.model.enums.DetectorType:Id_1, ome.model.enums.DetectorType:Id_2, ome.model.enums.DetectorType:Id_3, ... 12 more)
2010-09-06 06:31:16,356 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,356 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [class ome.model.enums.LaserMedium]
2010-09-06 06:31:16,357 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,367 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1283772676356] time[11] tag[omero.call.success]
2010-09-06 06:31:16,367 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: (ome.model.enums.LaserMedium:Id_1, ome.model.enums.LaserMedium:Id_2, ome.model.enums.LaserMedium:Id_3, ... 32 more)
2010-09-06 06:31:16,384 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,384 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Args: [class ome.model.enums.LaserType]
2010-09-06 06:31:16,385 INFO [ ome.security.basic.EventHandler] (l.Server-3) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,390 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1283772676384] time[6] tag[omero.call.success]
2010-09-06 06:31:16,390 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Rslt: (ome.model.enums.LaserType:Id_1, ome.model.enums.LaserType:Id_2, ome.model.enums.LaserType:Id_3, ... 6 more)
2010-09-06 06:31:16,418 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Meth: interface ome.api.IPixels.retrievePixDescription
2010-09-06 06:31:16,418 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Args: [197]
2010-09-06 06:31:16,419 INFO [ ome.security.basic.EventHandler] (l.Server-6) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,420 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,420 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Args: [class ome.model.enums.Pulse]
2010-09-06 06:31:16,421 INFO [ ome.security.basic.EventHandler] (l.Server-8) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,424 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1283772676420] time[4] tag[omero.call.success]
2010-09-06 06:31:16,424 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: (ome.model.enums.Pulse:Id_1, ome.model.enums.Pulse:Id_2, ome.model.enums.Pulse:Id_3, ... 4 more)
2010-09-06 06:31:16,435 INFO [ org.perf4j.TimingLogger] (l.Server-6) start[1283772676418] time[17] tag[omero.call.success]
2010-09-06 06:31:16,435 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Rslt: ome.model.core.Pixels:Id_197
2010-09-06 06:31:16,447 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Meth: interface ome.api.IPixels.retrievePixDescription
2010-09-06 06:31:16,447 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Args: [197]
2010-09-06 06:31:16,448 INFO [ ome.security.basic.EventHandler] (l.Server-7) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,456 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772676447] time[9] tag[omero.call.success]
2010-09-06 06:31:16,456 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Rslt: ome.model.core.Pixels:Id_197
2010-09-06 06:31:16,470 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,470 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Args: [class ome.model.enums.ArcType]
2010-09-06 06:31:16,471 INFO [ ome.security.basic.EventHandler] (l.Server-5) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,475 INFO [ org.perf4j.TimingLogger] (l.Server-5) start[1283772676470] time[5] tag[omero.call.success]
2010-09-06 06:31:16,475 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Rslt: (ome.model.enums.ArcType:Id_1, ome.model.enums.ArcType:Id_2, ome.model.enums.ArcType:Id_3, ... 2 more)
2010-09-06 06:31:16,493 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,494 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Args: [class ome.model.enums.FilamentType]
2010-09-06 06:31:16,495 INFO [ ome.security.basic.EventHandler] (l.Server-9) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,497 INFO [ org.perf4j.TimingLogger] (l.Server-9) start[1283772676494] time[3] tag[omero.call.success]
2010-09-06 06:31:16,498 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Rslt: (ome.model.enums.FilamentType:Id_1, ome.model.enums.FilamentType:Id_2, ome.model.enums.FilamentType:Id_3, ... 1 more)
2010-09-06 06:31:16,549 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,549 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Args: [class ome.model.enums.FilterType]
2010-09-06 06:31:16,550 INFO [ ome.security.basic.EventHandler] (l.Server-0) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,554 INFO [ org.perf4j.TimingLogger] (l.Server-0) start[1283772676549] time[5] tag[omero.call.success]
2010-09-06 06:31:16,554 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Rslt: (ome.model.enums.FilterType:Id_1, ome.model.enums.FilterType:Id_2, ome.model.enums.FilterType:Id_3, ... 5 more)
2010-09-06 06:31:16,565 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Meth: interface ome.api.IPixels.getAllEnumerations
2010-09-06 06:31:16,565 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Args: [class ome.model.enums.MicroscopeType]
2010-09-06 06:31:16,566 INFO [ ome.security.basic.EventHandler] (l.Server-2) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,568 INFO [ org.perf4j.TimingLogger] (l.Server-2) start[1283772676565] time[3] tag[omero.call.success]
2010-09-06 06:31:16,569 INFO [ ome.services.util.ServiceHandler] (l.Server-2) Rslt: (ome.model.enums.MicroscopeType:Id_1, ome.model.enums.MicroscopeType:Id_2, ome.model.enums.MicroscopeType:Id_3, ... 3 more)
2010-09-06 06:31:16,578 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.IPixels.retrieveAllRndSettings
2010-09-06 06:31:16,578 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Meth: interface ome.api.IPixels.retrieveAllRndSettings
2010-09-06 06:31:16,578 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [197, 3]
2010-09-06 06:31:16,578 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [197, 3]
2010-09-06 06:31:16,579 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,579 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=2,group=3,event=null(User),sess=2bf5e9e0-322d-43ee-a72d-45f3b287163b
2010-09-06 06:31:16,605 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1283772676578] time[27] tag[omero.call.success]
2010-09-06 06:31:16,605 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1283772676578] time[27] tag[omero.call.success]
2010-09-06 06:31:16,605 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: (ome.model.display.RenderingDef:Id_197)
2010-09-06 06:31:16,605 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: (ome.model.display.RenderingDef:Id_197)
2010-09-06 06:31:20,484 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-3) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/ccce589a-bc99-4d17-80a9-b2c28c31152fomero.grid.SharedResources(omero.grid._SharedResourcesTie@72fccde)
2010-09-06 06:31:20,485 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-3) Added servant to adapter: 2bf5e9e0-322d-43ee-a72d-45f3b287163b/ccce589a-bc99-4d17-80a9-b2c28c31152fomero.api.IScript(omero.api._IScriptTie@3af029d3)
2010-09-06 06:31:20,555 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Executor.doWork -- ome.services.scripts.ScriptRepoHelper.loadAll(true)
2010-09-06 06:31:20,556 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Args: [null, ome.tools.spring.InternalServiceFactory@41db0505]
2010-09-06 06:31:20,564 INFO [ ome.security.basic.EventHandler] (l.Server-7) Auth: user=0,group=0,event=5135(Internal),sess=0ddb8d3c-3464-40bd-93a0-23c0add6aa52
2010-09-06 06:31:20,625 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1283772680556] time[69] tag[omero.call.success]
2010-09-06 06:31:20,625 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Rslt: (ome.model.core.OriginalFile:Id_1, ome.model.core.OriginalFile:Id_2, ome.model.core.OriginalFile:Id_3, ... 8 more)
2010-09-06 06:31:20,627 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-5) Keep alive: 13ffc57a-cc2b-44e6-b25e-1aba82bb3264

Re: Using scripts in OMERO.insight

PostPosted: Mon Sep 06, 2010 6:09 pm
by jmoore
Hi, and thanks for the logs. The issue you are having is related to the following error in omeroinsight.log

hulet wrote:Caused by: omero.ValidationException
serverStackTrace = ""
serverExceptionClass = ""
message = "Can't find params for 10. Stderr is in file 301

---stderr---
File "./script", line 526
finally:
^
SyntaxError: invalid syntax
"


I've created a ticket for the issue: http://trac.openmicroscopy.org.uk/omero/ticket/2832

The issue is that the "except: raise" on line 5265 is invalid Python 2.4 syntax, though it works on Python 2.5. You should be able to remove that line and restart your server in order to correct the issue.

Let us know how it goes.
~Josh

Re: Using scripts in OMERO.insight

PostPosted: Mon Sep 06, 2010 6:34 pm
by hulet
Thanks for the quick reply. Commenting out "except: raise" in the Combine_Images.py did get me by the first error. Now I am getting another error. If this is a problem with Python 2.4 on the server then I'll look at upgrading to Python 2.5. Here is the new error from omeroinsight.log:

ms since start (2010-09-06 13:18:35,428) ** INFO **
[thread: Initializer][class: org.openmicroscopy.shoola.env.rnd.PixelsServicesFactory] -
Heap memory usage: max 518979584

31 ms since start (2010-09-06 13:18:35,459) ** INFO **
[thread: Initializer][class: org.openmicroscopy.shoola.env.init.TaskBarInit] -
Loaded L&F: com.sun.java.swing.plaf.windows.WindowsLookAndFeel

8750 ms since start (2010-09-06 13:18:44,178) ** INFO **
[thread: Initializer][class: org.openmicroscopy.shoola.env.data.login.LoginServiceImpl] -
Logged onto OMERO at: ome.bioinfo.ittc.ku.edu
User Name:secret -- Password: ********


38359 ms since start (2010-09-06 13:19:13,787) ** ERROR **
[thread: AWT-EventQueue-0][class: org.openmicroscopy.shoola.agents.metadata.ScriptLoader] -
Data Retrieval Failure: org.openmicroscopy.shoola.env.data.ScriptingException: Cannot load the script: 302
at org.openmicroscopy.shoola.env.data.OMEROGateway.loadScript(OMEROGateway.java:5587)
at org.openmicroscopy.shoola.env.data.OmeroImageServiceImpl.loadScript(OmeroImageServiceImpl.java:935)
at org.openmicroscopy.shoola.env.data.views.calls.ScriptsLoader$2.doCall(ScriptsLoader.java:97)
at org.openmicroscopy.shoola.env.data.views.BatchCall.doStep(BatchCall.java:145)
at org.openmicroscopy.shoola.util.concur.tasks.CompositeTask.doStep(CompositeTask.java:226)
at org.openmicroscopy.shoola.env.data.views.CompositeBatchCall.doStep(CompositeBatchCall.java:126)
at org.openmicroscopy.shoola.util.concur.tasks.ExecCommand.exec(ExecCommand.java:165)
at org.openmicroscopy.shoola.util.concur.tasks.ExecCommand.run(ExecCommand.java:274)
at org.openmicroscopy.shoola.util.concur.tasks.AsyncProcessor$Runner.run(AsyncProcessor.java:91)
at java.lang.Thread.run(Unknown Source)
Caused by: omero.ValidationException
serverStackTrace = ""
serverExceptionClass = ""
message = "Can't find params for 302. Stderr is in file 303

---stderr---
No handlers could be found for logger "omero.util.TempFileManager"
Traceback (most recent call last):
File "./script", line 44, in ?
import omero.util.script_utils as scriptUtil
File "/bio/rgs/mai/OMERO.server-Beta-4.2.0/lib/python/omero/util/script_utils.py", line 45, in ?
from omero.util.OmeroPopo import EllipseData as EllipseData
File "/bio/rgs/mai/OMERO.server-Beta-4.2.0/lib/python/omero/util/OmeroPopo.py", line 83
class DataObject():
^
SyntaxError: invalid syntax
"
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source)
at java.lang.reflect.Constructor.newInstance(Unknown Source)
at java.lang.Class.newInstance0(Unknown Source)
at java.lang.Class.newInstance(Unknown Source)
at IceInternal.BasicStream$DynamicUserExceptionFactory.createAndThrow(BasicStream.java:2243)
at IceInternal.BasicStream.throwException(BasicStream.java:1632)
at IceInternal.Outgoing.throwUserException(Outgoing.java:442)
at omero.api._IScriptDelM.getParams(_IScriptDelM.java:191)
at omero.api.IScriptPrxHelper.getParams(IScriptPrxHelper.java:254)
at omero.api.IScriptPrxHelper.getParams(IScriptPrxHelper.java:226)
at org.openmicroscopy.shoola.env.data.OMEROGateway.loadScript(OMEROGateway.java:5585)
... 9 more
Data Retrieval Failure: org.openmicroscopy.shoola.env.data.ScriptingException: Cannot load the script: 302
at org.openmicroscopy.shoola.env.data.OMEROGateway.loadScript(OMEROGateway.java:5587)
at org.openmicroscopy.shoola.env.data.OmeroImageServiceImpl.loadScript(OmeroImageServiceImpl.java:935)
at org.openmicroscopy.shoola.env.data.views.calls.ScriptsLoader$2.doCall(ScriptsLoader.java:97)
at org.openmicroscopy.shoola.env.data.views.BatchCall.doStep(BatchCall.java:145)
at org.openmicroscopy.shoola.util.concur.tasks.CompositeTask.doStep(CompositeTask.java:226)
at org.openmicroscopy.shoola.env.data.views.CompositeBatchCall.doStep(CompositeBatchCall.java:126)
at org.openmicroscopy.shoola.util.concur.tasks.ExecCommand.exec(ExecCommand.java:165)
at org.openmicroscopy.shoola.util.concur.tasks.ExecCommand.run(ExecCommand.java:274)
at org.openmicroscopy.shoola.util.concur.tasks.AsyncProcessor$Runner.run(AsyncProcessor.java:91)
at java.lang.Thread.run(Unknown Source)
Caused by: omero.ValidationException
serverStackTrace = ""
serverExceptionClass = ""
message = "Can't find params for 302. Stderr is in file 303

---stderr---
No handlers could be found for logger "omero.util.TempFileManager"
Traceback (most recent call last):
File "./script", line 44, in ?
import omero.util.script_utils as scriptUtil
File "/bio/rgs/mai/OMERO.server-Beta-4.2.0/lib/python/omero/util/script_utils.py", line 45, in ?
from omero.util.OmeroPopo import EllipseData as EllipseData
File "/bio/rgs/mai/OMERO.server-Beta-4.2.0/lib/python/omero/util/OmeroPopo.py", line 83
class DataObject():
^
SyntaxError: invalid syntax
"
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source)
at java.lang.reflect.Constructor.newInstance(Unknown Source)
at java.lang.Class.newInstance0(Unknown Source)
at java.lang.Class.newInstance(Unknown Source)
at IceInternal.BasicStream$DynamicUserExceptionFactory.createAndThrow(BasicStream.java:2243)
at IceInternal.BasicStream.throwException(BasicStream.java:1632)
at IceInternal.Outgoing.throwUserException(Outgoing.java:442)
at omero.api._IScriptDelM.getParams(_IScriptDelM.java:191)
at omero.api.IScriptPrxHelper.getParams(IScriptPrxHelper.java:254)
at omero.api.IScriptPrxHelper.getParams(IScriptPrxHelper.java:226)
at org.openmicroscopy.shoola.env.data.OMEROGateway.loadScript(OMEROGateway.java:5585)
... 9 more
Exception in thread "AWT-EventQueue-0"

Re: Using scripts in OMERO.insight

PostPosted: Mon Sep 06, 2010 6:55 pm
by jmoore
Sorry to be putting you through this. As you can tell, we test far more regularly on Python2.5 and Python2.6, and certainly appreciate your help. Again, I've added a ticket: https://trac.openmicroscopy.org.uk/omero/ticket/2834. Again, you can alter the line, by either removing the parens:
Code: Select all
class DataObject:


or by adding "object" between them:

Code: Select all
class DataObject(object):


Alternatively, you could try upgrading to Python 2.5.

Warm regards,
~Josh.

Re: Using scripts in OMERO.insight

PostPosted: Tue Sep 07, 2010 4:25 am
by hulet
Your change for OmeroPopo.py fixed my script problem, Thanks! I'm more than happy to be the python 2.4 test case. We are running CentOS and they haven't released a python 2.5 yum upgrade yet.

Re: Using scripts in OMERO.insight

PostPosted: Tue Sep 07, 2010 6:23 am
by jmoore
CentOS is the primary reason we try to have Python2.4 support. Thanks for helping out! ~Josh.

Re: Using scripts in OMERO.insight

PostPosted: Fri Dec 10, 2010 4:56 am
by jennBakal
hi,
we're having the same error (Cannot load the script; contact the administrator). the error message i show at the command line on the client machine is a permission denied error for a temp file:
Permission denied: '/afs/cs.cmu.edu/user/bhcho/omero/tmp/omero_bhcho/18207/process7r_LFG.dir'
(more detailed error message attached below)

when i checked the directory 18207, it was not created with write permissions for other than the owner (when i look at other directories in omero_bhcho, some of them have full write permissions and some of them don't). i changed the permissions on the directory but get the same permission issue (different process file name).

this happens for scripts in the Analysis Scripts, Import Scripts and Util Scripts directories. the ones in Export Scripts and Figure Scripts open the dialogue but fail to work.

let me know what other info you need. FYI, we're using Python 2.5.2

thanks,
jenn


omero.InternalException
serverStackTrace = "Traceback (most recent call last):
File "/usr0/local/omero.server/OMERO.server-Beta-4.2.1/lib/python/omero/util/decorators.py", line 61, in exc_handler
rv = func(*args, **kwargs)
File "/usr0/local/omero.server/OMERO.server-Beta-4.2.1/lib/python/omero/processor.py", line 799, in parseJob
prx, process = self.process(client, session, job, current, None, properties, iskill)
File "/usr0/local/omero.server/OMERO.server-Beta-4.2.1/lib/python/omero/util/decorators.py", line 28, in handler
return func(*args, **kwargs)
File "/usr0/local/omero.server/OMERO.server-Beta-4.2.1/lib/python/omero/processor.py", line 858, in process
process = ProcessI(self.ctx, "python", properties, params, iskill, omero_home = self.omero_home)
File "/usr0/local/omero.server/OMERO.server-Beta-4.2.1/lib/python/omero/processor.py", line 117, in __init__
self.make_files()
File "/usr0/local/omero.server/OMERO.server-Beta-4.2.1/lib/python/omero/processor.py", line 140, in make_files
self.dir = create_path("process", ".dir", folder = True)
File "/usr0/local/omero.server/OMERO.server-Beta-4.2.1/lib/python/omero/util/temp_files.py", line 338, in create_path
return manager.create_path(prefix, suffix, folder = folder)
File "/usr0/local/omero.server/OMERO.server-Beta-4.2.1/lib/python/omero/util/temp_files.py", line 266, in create_path
name = tempfile.mkdtemp(prefix = prefix, suffix = suffix, dir = self.dir)
File "/usr/lib64/python2.5/tempfile.py", line 328, in mkdtemp
_os.mkdir(file, 0700)
OSError: [Errno 13] Permission denied: '/afs/cs.cmu.edu/user/bhcho/omero/tmp/omero_bhcho/18207/process7r_LFG.dir'