2014-04-04 13:19:19,807 INFO [ ome.services.blitz.Entry] ( main) Creating OMERO.blitz. Please wait... 2014-04-04 13:19:20,962 INFO [.s.ShutdownSafeEhcacheManagerFactoryBean] ( main) Initializing EHCache CacheManager 2014-04-04 13:19:21,414 INFO [ ome.services.util.DBPatchCheck] ( main) Verified database patch: OMERO5.0__0 2014-04-04 13:19:21,461 INFO [ ome.services.util.DBUserCheck] ( main) User root.id = 0 2014-04-04 13:19:21,461 INFO [ ome.services.util.DBUserCheck] ( main) User guest.id = 1 2014-04-04 13:19:21,477 INFO [ ome.services.util.DBUserCheck] ( main) Group system.id = 0 2014-04-04 13:19:21,477 INFO [ ome.services.util.DBUserCheck] ( main) Group user.id = 1 2014-04-04 13:19:21,477 INFO [ ome.services.util.DBUserCheck] ( main) Group guest.id = 2 2014-04-04 13:19:24,253 INFO [ ome.services.fulltext.FullTextAnalyzer] ( main) Initialized FullTextAnalyzer 2014-04-04 13:19:27,935 INFO [ ome.services.db.DatabaseIdentity] ( main) Using LSID format: urn:lsid:export.openmicroscopy.org:%s:210e9d11-5f29-4faf-a5e4-4aeb09fd127b_%s%s 2014-04-04 13:19:28,294 INFO [ org.perf4j.TimingLogger] ( main) start[1396631968013] time[277] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:28,372 INFO [ org.perf4j.TimingLogger] ( main) start[1396631968294] time[87] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:28,512 INFO [ org.perf4j.TimingLogger] ( main) start[1396631968372] time[134] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:28,606 INFO [ org.perf4j.TimingLogger] ( main) start[1396631968512] time[96] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:28,699 INFO [ org.perf4j.TimingLogger] ( main) start[1396631968606] time[91] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,043 INFO [ org.perf4j.TimingLogger] ( main) start[1396631968699] time[332] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,105 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969043] time[72] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,183 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969105] time[72] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,261 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969183] time[72] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,323 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969261] time[72] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,401 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969323] time[73] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,479 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969401] time[73] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,667 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969479] time[184] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,745 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969667] time[89] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,838 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969745] time[86] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,916 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969838] time[84] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:29,994 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969916] time[76] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:30,088 INFO [ org.perf4j.TimingLogger] ( main) start[1396631969994] time[94] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:30,181 INFO [ org.perf4j.TimingLogger] ( main) start[1396631970088] time[94] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:30,681 INFO [ org.perf4j.TimingLogger] ( main) start[1396631970181] time[496] tag[ome.io.nio.SimpleBackOff.256X256] 2014-04-04 13:19:30,696 INFO [ ome.io.nio.PixelsService] ( main) Using Bio-Formats Cache: F:\OMERO2\BioFormatsCache 2014-04-04 13:19:30,696 INFO [ ome.io.nio.PixelsService] ( main) PixelsService(path=F:\\OMERO2, resolver=ome.services.OmeroFilePathResolver@14c1ba9, backoff=ome.io.nio.SimpleBackOff(factor=134.9), sizes=ome.io.nio.ConfiguredTileSizes(w=256,h=256,W=3192,H=3192)) 2014-04-04 13:19:30,930 INFO [ ome.services.fulltext.FullTextThread] ( main) Initializing Full-Text Indexer 2014-04-04 13:19:30,946 INFO [ ome.services.pixeldata.PixelDataThread] ( main) Initializing PixelDataThread (create events only) 2014-04-04 13:19:30,946 INFO [ ome.services.util.JvmSettingsCheck] ( main) Java version: 1.8.0; Windows Server 2008 R2; x86; 6.1 2014-04-04 13:19:30,946 INFO [ ome.services.util.JvmSettingsCheck] ( main) Max Memory (MB): = 494 2014-04-04 13:19:30,961 INFO [ ome.services.util.JvmSettingsCheck] ( main) OS Memory (MB): = 32757 2014-04-04 13:19:30,961 INFO [ ome.services.util.JvmSettingsCheck] ( main) Processors: = 16 2014-04-04 13:19:31,383 INFO [ ome.services.util.ServerVersionCheck] ( main) ------------------------------------------------- 2014-04-04 13:19:31,383 INFO [ ome.services.util.ServerVersionCheck] ( main) OMERO Version: 5.0.0-ice34-b19 Ready. 2014-04-04 13:19:31,383 INFO [ ome.services.util.ServerVersionCheck] ( main) ------------------------------------------------- 2014-04-04 13:19:32,521 INFO [ ome.system.UpgradeCheck] ( main) no update needed 2014-04-04 13:19:32,537 INFO [ ome.tools.hibernate.ExtendedMetadata] ( main) Calculating ExtendedMetadata... 2014-04-04 13:19:32,646 INFO [ o.s.scheduler.SchedulerFactoryBean] ( main) Starting Quartz Scheduler now 2014-04-04 13:19:32,662 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.util.NamespaceCheck.namespaceCheck 2014-04-04 13:19:32,662 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, InternalSF@5489205] 2014-04-04 13:19:32,911 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=2751(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,114 INFO [ org.perf4j.TimingLogger] ( main) start[1396631972662] time[464] tag[omero.call.success.ome.services.util.NamespaceCheck$1.doWork] 2014-04-04 13:19:33,114 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: null 2014-04-04 13:19:33,348 INFO [o.services.blitz.util.BlitzConfiguration] ( main) Initializing Ice.Communicator 2014-04-04 13:19:33,520 INFO [ ome.services.blitz.fire.Ring] ( main) Checking cluster 2014-04-04 13:19:33,535 INFO [ ome.services.blitz.fire.Registry] ( main) Found 0 cluster node(s) : [] 2014-04-04 13:19:33,535 INFO [ ome.services.blitz.fire.Ring] ( main) Got 0 cluster uuids : [] 2014-04-04 13:19:33,535 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.getManagerList 2014-04-04 13:19:33,535 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, InternalSF@5489205] 2014-04-04 13:19:33,551 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=null(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,582 INFO [ org.perf4j.TimingLogger] ( main) start[1396631973535] time[42] tag[omero.call.success.ome.services.blitz.fire.Ring$2.doWork] 2014-04-04 13:19:33,582 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: (59959d99-840b-4173-b156-467acadb00e9) 2014-04-04 13:19:33,582 INFO [ ome.services.blitz.fire.Ring] ( main) Purging node: 59959d99-840b-4173-b156-467acadb00e9 2014-04-04 13:19:33,613 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest) 2014-04-04 13:19:33,613 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [null, InternalSF@5489205] 2014-04-04 13:19:33,613 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,629 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1396631973613] time[7] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:33,629 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: true 2014-04-04 13:19:33,629 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest) 2014-04-04 13:19:33,629 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Args: [null, InternalSF@5489205] 2014-04-04 13:19:33,629 INFO [ ome.security.basic.EventHandler] (l.Server-6) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,645 INFO [ org.perf4j.TimingLogger] (l.Server-6) start[1396631973629] time[7] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:33,645 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Rslt: true 2014-04-04 13:19:33,676 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.executeUpdate - set closed = now() 2014-04-04 13:19:33,676 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, InternalSF@5489205] 2014-04-04 13:19:33,691 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=2752(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,707 INFO [ org.perf4j.TimingLogger] ( main) start[1396631973676] time[30] tag[omero.call.success.ome.services.blitz.fire.Ring$3.doWork] 2014-04-04 13:19:33,707 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: 0 2014-04-04 13:19:33,707 INFO [ ome.services.blitz.fire.Ring] ( main) Removed 0 entries with value 59959d99-840b-4173-b156-467acadb00e9 2014-04-04 13:19:33,707 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.setManagerDown 2014-04-04 13:19:33,707 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, InternalSF@5489205] 2014-04-04 13:19:33,723 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=2753(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,723 INFO [ org.perf4j.TimingLogger] ( main) start[1396631973707] time[20] tag[omero.call.success.ome.services.blitz.fire.Ring$4.doWork] 2014-04-04 13:19:33,723 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: 1 2014-04-04 13:19:33,723 INFO [ ome.services.blitz.fire.Ring] ( main) Removed manager: 59959d99-840b-4173-b156-467acadb00e9 2014-04-04 13:19:33,723 INFO [ ome.services.blitz.fire.Ring] ( main) handleRingShutdown: 59959d99-840b-4173-b156-467acadb00e9 2014-04-04 13:19:33,723 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.addManager 2014-04-04 13:19:33,723 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, InternalSF@5489205] 2014-04-04 13:19:33,754 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=2754(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,754 INFO [ ome.security.basic.CurrentDetails] ( main) Adding log:INSERT,class ome.model.meta.Node,2651 2014-04-04 13:19:33,754 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest) 2014-04-04 13:19:33,754 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Args: [null, InternalSF@5489205] 2014-04-04 13:19:33,754 INFO [ ome.security.basic.EventHandler] (l.Server-8) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,769 INFO [ org.perf4j.TimingLogger] ( main) start[1396631973723] time[33] tag[omero.call.success.ome.services.blitz.fire.Ring$5.doWork] 2014-04-04 13:19:33,769 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: ome.model.meta.Node:Id_2651 2014-04-04 13:19:33,769 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1396631973754] time[8] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:33,769 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: true 2014-04-04 13:19:33,769 INFO [ ome.services.blitz.fire.Registry] ( main) Added ClusterNode/63fbe282-68b5-43ef-9b82-0b358ec111d6 to registry 2014-04-04 13:19:33,769 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest) 2014-04-04 13:19:33,769 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [null, InternalSF@5489205] 2014-04-04 13:19:33,769 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:33,785 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1396631973769] time[7] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:33,785 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: true 2014-04-04 13:19:34,097 INFO [ o.s.blitz.repo.AbstractRepositoryI] ( main) Initializing repository in F:\\OMERO2 2014-04-04 13:19:34,128 INFO [o.services.blitz.repo.ManagedRepositoryI] ( main) Repository template: %user%_%userId%/%year%-%month%/%day%/%time% 2014-04-04 13:19:34,128 INFO [ o.s.blitz.repo.AbstractRepositoryI] ( main) Initializing repository in F:\\OMERO2/ManagedRepository 2014-04-04 13:19:34,144 INFO [ o.s.blitz.repo.AbstractRepositoryI] ( main) Initializing repository in C:\OMERO.server\.\lib\scripts 2014-04-04 13:19:34,206 INFO [ ome.services.util.ServiceHandler] (3-thread-4) Executor.doWork -- ome.services.blitz.repo.ScriptRepositoryI.takeover 2014-04-04 13:19:34,206 INFO [ ome.services.util.ServiceHandler] (3-thread-2) Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.takeover 2014-04-04 13:19:34,206 INFO [ ome.services.util.ServiceHandler] (3-thread-4) Args: [null, InternalSF@5489205] 2014-04-04 13:19:34,206 INFO [ ome.services.util.ServiceHandler] (3-thread-2) Args: [null, InternalSF@5489205] 2014-04-04 13:19:34,206 INFO [ ome.services.util.ServiceHandler] (3-thread-3) Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.takeover 2014-04-04 13:19:34,206 INFO [ ome.services.util.ServiceHandler] (3-thread-3) Args: [null, InternalSF@5489205] 2014-04-04 13:19:34,237 INFO [ ome.services.blitz.util.CheckAllJobs] ( Thread-8) Waiting 120 secs. for callbacks 2014-04-04 13:19:34,237 INFO [ ome.services.scripts.ScriptRepoHelper] ( main) Registering MatlabScripts: WildcardFileFilter(*.m) 2014-04-04 13:19:34,237 INFO [ ome.services.scripts.ScriptRepoHelper] ( main) Registering PythonScripts: WildcardFileFilter(*.py) 2014-04-04 13:19:34,237 INFO [ ome.services.scripts.ScriptRepoHelper] ( main) Registering JythonScripts: WildcardFileFilter(*.jy) 2014-04-04 13:19:34,237 INFO [ ome.security.basic.EventHandler] (3-thread-4) Auth: user=0,group=0,event=2755(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:34,237 INFO [ o.s.blitz.repo.AbstractRepositoryI] (3-thread-4) Opened repository scripts (uuid=ScriptRepo) 2014-04-04 13:19:34,253 INFO [ ome.security.basic.EventHandler] (3-thread-3) Auth: user=0,group=0,event=2756(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:34,253 INFO [ o.s.blitz.repo.AbstractRepositoryI] (3-thread-3) Opened repository ManagedRepository (uuid=69b60b5f-cec8-42f6-8c33-668585685e61) 2014-04-04 13:19:34,269 INFO [ ome.services.blitz.fire.Registry] (3-thread-4) Updated InternalRepository-ScriptRepo in registry 2014-04-04 13:19:34,284 INFO [ ome.security.basic.EventHandler] (3-thread-2) Auth: user=0,group=0,event=2757(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:34,284 INFO [ o.s.blitz.repo.AbstractRepositoryI] (3-thread-2) Opened repository OMERO2 (uuid=f06f1c35-1689-4283-ac0b-412f83a5cbd4) 2014-04-04 13:19:34,315 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.scripts.ScriptRepoHelper.loadAll(true) 2014-04-04 13:19:34,315 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, InternalSF@5489205] 2014-04-04 13:19:34,315 INFO [ ome.services.blitz.fire.Registry] (3-thread-3) Updated InternalRepository-69b60b5f-cec8-42f6-8c33-668585685e61 in registry 2014-04-04 13:19:34,331 INFO [ ome.services.blitz.fire.Registry] (3-thread-2) Updated InternalRepository-f06f1c35-1689-4283-ac0b-412f83a5cbd4 in registry 2014-04-04 13:19:34,331 INFO [ ome.services.blitz.fire.Registry] (3-thread-4) Updated PublicRepository-ScriptRepo in registry 2014-04-04 13:19:34,331 INFO [ ome.services.blitz.fire.Registry] (3-thread-3) Updated PublicRepository-69b60b5f-cec8-42f6-8c33-668585685e61 in registry 2014-04-04 13:19:34,331 INFO [ o.s.blitz.repo.AbstractRepositoryI] (3-thread-4) Repository now active 2014-04-04 13:19:34,331 INFO [ o.s.blitz.repo.AbstractRepositoryI] (3-thread-3) Repository now active 2014-04-04 13:19:34,331 INFO [ ome.services.blitz.fire.Registry] (3-thread-2) Updated PublicRepository-f06f1c35-1689-4283-ac0b-412f83a5cbd4 in registry 2014-04-04 13:19:34,331 INFO [ o.s.blitz.repo.AbstractRepositoryI] (3-thread-2) Repository now active 2014-04-04 13:19:34,347 INFO [ org.perf4j.TimingLogger] (3-thread-3) start[1396631974206] time[131] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$GetOrCreateRepo.doWork] 2014-04-04 13:19:34,347 INFO [ org.perf4j.TimingLogger] (3-thread-2) start[1396631974206] time[131] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$GetOrCreateRepo.doWork] 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-3) Rslt: ome.model.core.OriginalFile:Id_2 2014-04-04 13:19:34,347 INFO [ org.perf4j.TimingLogger] (3-thread-4) start[1396631974206] time[131] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$GetOrCreateRepo.doWork] 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-2) Rslt: ome.model.core.OriginalFile:Id_3 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-4) Rslt: ome.model.core.OriginalFile:Id_1 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-4) Executor.doWork -- ome.services.blitz.repo.ScriptRepositoryI.getDescription(1) 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-3) Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.getDescription(2) 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-2) Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.getDescription(3) 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-3) Args: [null, InternalSF@5489205] 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-4) Args: [null, InternalSF@5489205] 2014-04-04 13:19:34,347 INFO [ ome.services.util.ServiceHandler] (3-thread-2) Args: [null, InternalSF@5489205] 2014-04-04 13:19:34,347 INFO [ ome.security.basic.EventHandler] (3-thread-3) Auth: user=0,group=0,event=null(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:34,347 INFO [ ome.security.basic.EventHandler] (3-thread-2) Auth: user=0,group=0,event=null(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:34,347 INFO [ ome.security.basic.EventHandler] (3-thread-4) Auth: user=0,group=0,event=null(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:34,362 INFO [ org.perf4j.TimingLogger] (3-thread-3) start[1396631974347] time[16] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$1.doWork] 2014-04-04 13:19:34,362 INFO [ ome.services.util.ServiceHandler] (3-thread-3) Rslt: ome.model.core.OriginalFile:Id_2 2014-04-04 13:19:34,362 INFO [ org.perf4j.TimingLogger] (3-thread-4) start[1396631974347] time[18] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$1.doWork] 2014-04-04 13:19:34,362 INFO [ org.perf4j.TimingLogger] (3-thread-2) start[1396631974347] time[18] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$1.doWork] 2014-04-04 13:19:34,362 INFO [ ome.services.util.ServiceHandler] (3-thread-4) Rslt: ome.model.core.OriginalFile:Id_1 2014-04-04 13:19:34,362 INFO [ ome.services.util.ServiceHandler] (3-thread-2) Rslt: ome.model.core.OriginalFile:Id_3 2014-04-04 13:19:34,362 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=2758(Internal),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:34,456 INFO [ org.perf4j.TimingLogger] ( main) start[1396631974315] time[143] tag[omero.call.success.ome.services.scripts.ScriptRepoHelper$5.doWork] 2014-04-04 13:19:34,456 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: (ome.model.core.OriginalFile:Id_4, ome.model.core.OriginalFile:Id_5, ome.model.core.OriginalFile:Id_6, ... 13 more) 2014-04-04 13:19:34,471 INFO [ ome.services.blitz.Entry] ( main) OMERO.blitz now accepting connections. 2014-04-04 13:19:37,997 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest) 2014-04-04 13:19:37,997 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Args: [null, InternalSF@5489205] 2014-04-04 13:19:37,997 INFO [ ome.security.basic.EventHandler] (l.Server-9) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:37,997 INFO [ org.perf4j.TimingLogger] (l.Server-9) start[1396631977997] time[5] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:37,997 INFO [ ome.services.util.ServiceHandler] (l.Server-9) Rslt: true 2014-04-04 13:19:37,997 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession 2014-04-04 13:19:37,997 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Args: [null, InternalSF@5489205] 2014-04-04 13:19:38,013 INFO [ ome.security.basic.EventHandler] (l.Server-0) Auth: user=0,group=0,event=2759(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:38,059 INFO [ ome.security.basic.CurrentDetails] (l.Server-0) Adding log:INSERT,class ome.model.meta.Session,8167 2014-04-04 13:19:38,106 INFO [ org.perf4j.TimingLogger] (l.Server-0) start[1396631977997] time[96] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork] 2014-04-04 13:19:38,106 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Rslt: (ome.model.meta.Experimenter:Id_1, ome.model.meta.ExperimenterGroup:Id_2, (2), ... 4 more) 2014-04-04 13:19:38,137 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-0) Created session ServiceFactoryI(session-310d5ca3-dab5-4fc9-924b-3997adc13f80/f65a56df-c712-40a7-89e0-f2b9a25a7814) for user guest (agent=OMERO.web) 2014-04-04 13:19:38,231 INFO [ omero.cmd.SessionI] (l.Server-3) Added servant to adapter: f65a56df-c712-40a7-89e0-f2b9a25a7814/310d5ca3-dab5-4fc9-924b-3997adc13f80omero.api.ISession(omero.api._ISessionTie@19ea5a15) 2014-04-04 13:19:38,262 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Meth: interface ome.api.ISession.getSession 2014-04-04 13:19:38,262 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [f65a56df-c712-40a7-89e0-f2b9a25a7814] 2014-04-04 13:19:38,262 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1396631978262] time[0] tag[omero.call.success.ome.services.sessions.SessionBean.getSession] 2014-04-04 13:19:38,262 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: ome.model.meta.Session:Id_8167 2014-04-04 13:19:38,309 INFO [ omero.cmd.SessionI] (l.Server-5) Added servant to adapter: f65a56df-c712-40a7-89e0-f2b9a25a7814/310d5ca3-dab5-4fc9-924b-3997adc13f80omero.api.IConfig(omero.api._IConfigTie@1953070b) 2014-04-04 13:19:38,309 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Meth: interface ome.api.IConfig.getConfigValue 2014-04-04 13:19:38,309 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Args: [omero.router.insecure] 2014-04-04 13:19:38,325 INFO [ ome.security.basic.EventHandler] (l.Server-6) Auth: user=1,group=2,event=2760(User),sess=f65a56df-c712-40a7-89e0-f2b9a25a7814 2014-04-04 13:19:38,340 INFO [ org.perf4j.TimingLogger] (l.Server-6) start[1396631978309] time[21] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue] 2014-04-04 13:19:38,340 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Rslt: OMERO.Glacier2/router:tcp -p 4063 -h @omero.host@ 2014-04-04 13:19:38,340 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-8) Found session locally: f65a56df-c712-40a7-89e0-f2b9a25a7814 2014-04-04 13:19:38,340 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-8) Rejoining session ServiceFactoryI(session-186cbbe6-5cba-4e52-9e9c-c7b1747df1ba/f65a56df-c712-40a7-89e0-f2b9a25a7814) (agent=OMERO.web;secure=False) 2014-04-04 13:19:38,356 INFO [o.services.sessions.SessionContext$Count] (l.Server-0) -Reference count: f65a56df-c712-40a7-89e0-f2b9a25a7814=1 2014-04-04 13:19:38,356 INFO [ omero.cmd.SessionI] (l.Server-0) cleanupSelf(ServiceFactoryI(session-310d5ca3-dab5-4fc9-924b-3997adc13f80/f65a56df-c712-40a7-89e0-f2b9a25a7814)). 2014-04-04 13:19:38,356 INFO [ omero.cmd.SessionI] (l.Server-0) Unregistered servant:f65a56df-c712-40a7-89e0-f2b9a25a7814/310d5ca3-dab5-4fc9-924b-3997adc13f80omero.api.ISession(omero.api._ISessionTie@19ea5a15) 2014-04-04 13:19:38,356 INFO [ omero.cmd.SessionI] (l.Server-0) Removed servant from adapter: 310d5ca3-dab5-4fc9-924b-3997adc13f80omero.api.ISession 2014-04-04 13:19:38,356 INFO [ omero.cmd.SessionI] (l.Server-0) Unregistered servant:f65a56df-c712-40a7-89e0-f2b9a25a7814/310d5ca3-dab5-4fc9-924b-3997adc13f80omero.api.IConfig(omero.api._IConfigTie@1953070b) 2014-04-04 13:19:38,356 INFO [ omero.cmd.SessionI] (l.Server-0) Removed servant from adapter: 310d5ca3-dab5-4fc9-924b-3997adc13f80omero.api.IConfig 2014-04-04 13:19:38,356 INFO [ omero.cmd.SessionI] (l.Server-2) Added servant to adapter: f65a56df-c712-40a7-89e0-f2b9a25a7814/186cbbe6-5cba-4e52-9e9c-c7b1747df1baomero.api.IConfig(omero.api._IConfigTie@19347de4) 2014-04-04 13:19:38,356 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Meth: interface ome.api.IConfig.getConfigValue 2014-04-04 13:19:38,356 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Args: [omero.version] 2014-04-04 13:19:38,371 INFO [ ome.security.basic.EventHandler] (l.Server-3) Auth: user=1,group=2,event=2761(User),sess=f65a56df-c712-40a7-89e0-f2b9a25a7814 2014-04-04 13:19:38,371 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1396631978356] time[19] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue] 2014-04-04 13:19:38,371 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Rslt: 5.0.0-ice34-b19 2014-04-04 13:19:38,465 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(jason.kotecki) 2014-04-04 13:19:38,465 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [null, InternalSF@5489205] 2014-04-04 13:19:38,481 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:38,481 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1396631978465] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:38,481 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: null 2014-04-04 13:19:38,481 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRW(jason.kotecki) 2014-04-04 13:19:38,481 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [null, InternalSF@5489205] 2014-04-04 13:19:38,496 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=0,group=0,event=2762(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:38,668 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1396631978481] time[191] tag[omero.call.exception] 2014-04-04 13:19:38,683 WARN [ ome.services.util.ServiceHandler] (l.Server-4) Unknown exception thrown. java.lang.ClassCastException: [B cannot be cast to java.lang.Comparable at java.util.TreeMap.compare(Unknown Source) ~[na:1.8.0] at java.util.TreeMap.put(Unknown Source) ~[na:1.8.0] at java.util.TreeSet.add(Unknown Source) ~[na:1.8.0] at org.springframework.ldap.support.LdapUtils$CollectingAttributeValueCallbackHandler.handleAttributeValue(LdapUtils.java:291) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.support.LdapUtils.iterateAttributeValues(LdapUtils.java:268) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.support.LdapUtils.collectAttributeValues(LdapUtils.java:252) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.DirContextAdapter.getAttributeSortedStringSet(DirContextAdapter.java:785) ~[spring-ldap.jar:1.3.0.RELEASE] at ome.security.auth.AttributeSet.(AttributeSet.java:46) ~[server.jar:na] at ome.security.auth.PersonContextMapper.mapFromContext(PersonContextMapper.java:70) ~[server.jar:na] at org.springframework.ldap.core.ContextMapperCallbackHandler.getObjectFromNameClassPair(ContextMapperCallbackHandler.java:67) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.CollectingNameClassPairCallbackHandler.handleNameClassPair(CollectingNameClassPairCallbackHandler.java:50) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:297) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:259) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:606) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:524) ~[spring-ldap.jar:1.3.0.RELEASE] at ome.logic.LdapImpl.mapUserName(LdapImpl.java:201) ~[server.jar:na] at ome.logic.LdapImpl.findExperimenter(LdapImpl.java:183) ~[server.jar:na] at ome.logic.LdapImpl.createUserFromLdap(LdapImpl.java:388) ~[server.jar:na] at ome.security.auth.LdapPasswordProvider.checkPassword(LdapPasswordProvider.java:112) ~[server.jar:na] at ome.security.auth.PasswordProviders.checkPassword(PasswordProviders.java:42) ~[server.jar:na] at ome.logic.AdminImpl.checkPassword(AdminImpl.java:1203) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl$9.doWork(SessionManagerImpl.java:979) ~[server.jar:na] at sun.reflect.GeneratedMethodAccessor223.invoke(Unknown Source) ~[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0] at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) [org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) [org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.services.util.Executor$Impl$Interceptor.invoke(Executor.java:576) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.security.basic.EventHandler.invoke(EventHandler.java:154) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111) ~[org.springframework.orm.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108) ~[org.springframework.transaction.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:241) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202) [org.springframework.aop.jar:3.0.1.RELEASE-A] at com.sun.proxy.$Proxy71.doWork(Unknown Source) [na:na] at ome.services.util.Executor$Impl.execute(Executor.java:457) [server.jar:na] at ome.services.util.Executor$Impl.execute(Executor.java:401) [server.jar:na] at ome.services.sessions.SessionManagerImpl.executeCheckPasswordRW(SessionManagerImpl.java:974) [server.jar:na] at ome.services.sessions.SessionManagerImpl.executeCheckPassword(SessionManagerImpl.java:946) [server.jar:na] at ome.services.sessions.SessionManagerImpl.executePasswordCheck(SessionManagerImpl.java:921) [server.jar:na] at ome.services.blitz.fire.PermissionsVerifierI.checkPermissions(PermissionsVerifierI.java:135) [blitz.jar:na] at Glacier2._PermissionsVerifierDisp.___checkPermissions(_PermissionsVerifierDisp.java:118) [ice.jar:na] at Glacier2._PermissionsVerifierDisp.__dispatch(_PermissionsVerifierDisp.java:146) [ice.jar:na] at IceInternal.Incoming.invoke(Incoming.java:159) [ice.jar:na] at Ice.ConnectionI.invokeAll(ConnectionI.java:2357) [ice.jar:na] at Ice.ConnectionI.dispatch(ConnectionI.java:1208) [ice.jar:na] at Ice.ConnectionI.message(ConnectionI.java:1163) [ice.jar:na] at IceInternal.ThreadPool.run(ThreadPool.java:302) [ice.jar:na] at IceInternal.ThreadPool.access$300(ThreadPool.java:12) [ice.jar:na] at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:643) [ice.jar:na] at java.lang.Thread.run(Unknown Source) [na:1.8.0] 2014-04-04 13:19:38,683 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Excp: java.lang.ClassCastException: [B cannot be cast to java.lang.Comparable 2014-04-04 13:19:38,683 ERROR [ o.s.blitz.fire.PermissionsVerifierI] (l.Server-4) Exception thrown while checking password for:jason.kotecki ome.conditions.InternalException: Wrapped Exception: (java.lang.ClassCastException): [B cannot be cast to java.lang.Comparable at java.util.TreeMap.compare(Unknown Source) ~[na:1.8.0] at java.util.TreeMap.put(Unknown Source) ~[na:1.8.0] at java.util.TreeSet.add(Unknown Source) ~[na:1.8.0] at org.springframework.ldap.support.LdapUtils$CollectingAttributeValueCallbackHandler.handleAttributeValue(LdapUtils.java:291) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.support.LdapUtils.iterateAttributeValues(LdapUtils.java:268) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.support.LdapUtils.collectAttributeValues(LdapUtils.java:252) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.DirContextAdapter.getAttributeSortedStringSet(DirContextAdapter.java:785) ~[spring-ldap.jar:1.3.0.RELEASE] at ome.security.auth.AttributeSet.(AttributeSet.java:46) ~[server.jar:na] at ome.security.auth.PersonContextMapper.mapFromContext(PersonContextMapper.java:70) ~[server.jar:na] at org.springframework.ldap.core.ContextMapperCallbackHandler.getObjectFromNameClassPair(ContextMapperCallbackHandler.java:67) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.CollectingNameClassPairCallbackHandler.handleNameClassPair(CollectingNameClassPairCallbackHandler.java:50) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:297) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:259) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:606) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:524) ~[spring-ldap.jar:1.3.0.RELEASE] at ome.logic.LdapImpl.mapUserName(LdapImpl.java:201) ~[server.jar:na] at ome.logic.LdapImpl.findExperimenter(LdapImpl.java:183) ~[server.jar:na] at ome.logic.LdapImpl.createUserFromLdap(LdapImpl.java:388) ~[server.jar:na] at ome.security.auth.LdapPasswordProvider.checkPassword(LdapPasswordProvider.java:112) ~[server.jar:na] at ome.security.auth.PasswordProviders.checkPassword(PasswordProviders.java:42) ~[server.jar:na] at ome.logic.AdminImpl.checkPassword(AdminImpl.java:1203) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl$9.doWork(SessionManagerImpl.java:979) ~[server.jar:na] at sun.reflect.GeneratedMethodAccessor223.invoke(Unknown Source) ~[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0] at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.services.util.Executor$Impl$Interceptor.invoke(Executor.java:576) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.security.basic.EventHandler.invoke(EventHandler.java:154) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111) ~[org.springframework.orm.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108) ~[org.springframework.transaction.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:241) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at com.sun.proxy.$Proxy71.doWork(Unknown Source) ~[na:na] at ome.services.util.Executor$Impl.execute(Executor.java:457) ~[server.jar:na] at ome.services.util.Executor$Impl.execute(Executor.java:401) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl.executeCheckPasswordRW(SessionManagerImpl.java:974) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl.executeCheckPassword(SessionManagerImpl.java:946) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl.executePasswordCheck(SessionManagerImpl.java:921) ~[server.jar:na] at ome.services.blitz.fire.PermissionsVerifierI.checkPermissions(PermissionsVerifierI.java:135) ~[blitz.jar:na] at Glacier2._PermissionsVerifierDisp.___checkPermissions(_PermissionsVerifierDisp.java:118) [ice.jar:na] at Glacier2._PermissionsVerifierDisp.__dispatch(_PermissionsVerifierDisp.java:146) [ice.jar:na] at IceInternal.Incoming.invoke(Incoming.java:159) [ice.jar:na] at Ice.ConnectionI.invokeAll(ConnectionI.java:2357) [ice.jar:na] at Ice.ConnectionI.dispatch(ConnectionI.java:1208) [ice.jar:na] at Ice.ConnectionI.message(ConnectionI.java:1163) [ice.jar:na] at IceInternal.ThreadPool.run(ThreadPool.java:302) [ice.jar:na] at IceInternal.ThreadPool.access$300(ThreadPool.java:12) [ice.jar:na] at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:643) [ice.jar:na] at java.lang.Thread.run(Unknown Source) [na:1.8.0] 2014-04-04 13:19:38,683 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(jason.kotecki) 2014-04-04 13:19:38,683 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Args: [null, InternalSF@5489205] 2014-04-04 13:19:38,683 INFO [ ome.security.basic.EventHandler] (l.Server-5) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:38,683 INFO [ org.perf4j.TimingLogger] (l.Server-5) start[1396631978683] time[4] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:38,683 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Rslt: null 2014-04-04 13:19:38,683 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRW(jason.kotecki) 2014-04-04 13:19:38,683 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Args: [null, InternalSF@5489205] 2014-04-04 13:19:38,699 INFO [ ome.security.basic.EventHandler] (l.Server-5) Auth: user=0,group=0,event=2763(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:38,715 INFO [ org.perf4j.TimingLogger] (l.Server-5) start[1396631978683] time[32] tag[omero.call.exception] 2014-04-04 13:19:38,730 WARN [ ome.services.util.ServiceHandler] (l.Server-5) Unknown exception thrown. java.lang.ClassCastException: [B cannot be cast to java.lang.Comparable at java.util.TreeMap.compare(Unknown Source) ~[na:1.8.0] at java.util.TreeMap.put(Unknown Source) ~[na:1.8.0] at java.util.TreeSet.add(Unknown Source) ~[na:1.8.0] at org.springframework.ldap.support.LdapUtils$CollectingAttributeValueCallbackHandler.handleAttributeValue(LdapUtils.java:291) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.support.LdapUtils.iterateAttributeValues(LdapUtils.java:268) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.support.LdapUtils.collectAttributeValues(LdapUtils.java:252) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.DirContextAdapter.getAttributeSortedStringSet(DirContextAdapter.java:785) ~[spring-ldap.jar:1.3.0.RELEASE] at ome.security.auth.AttributeSet.(AttributeSet.java:46) ~[server.jar:na] at ome.security.auth.PersonContextMapper.mapFromContext(PersonContextMapper.java:70) ~[server.jar:na] at org.springframework.ldap.core.ContextMapperCallbackHandler.getObjectFromNameClassPair(ContextMapperCallbackHandler.java:67) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.CollectingNameClassPairCallbackHandler.handleNameClassPair(CollectingNameClassPairCallbackHandler.java:50) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:297) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:259) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:606) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:524) ~[spring-ldap.jar:1.3.0.RELEASE] at ome.logic.LdapImpl.mapUserName(LdapImpl.java:201) ~[server.jar:na] at ome.logic.LdapImpl.findExperimenter(LdapImpl.java:183) ~[server.jar:na] at ome.logic.LdapImpl.createUserFromLdap(LdapImpl.java:388) ~[server.jar:na] at ome.security.auth.LdapPasswordProvider.checkPassword(LdapPasswordProvider.java:112) ~[server.jar:na] at ome.security.auth.PasswordProviders.checkPassword(PasswordProviders.java:42) ~[server.jar:na] at ome.logic.AdminImpl.checkPassword(AdminImpl.java:1203) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl$9.doWork(SessionManagerImpl.java:979) ~[server.jar:na] at sun.reflect.GeneratedMethodAccessor223.invoke(Unknown Source) ~[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0] at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) [org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) [org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.services.util.Executor$Impl$Interceptor.invoke(Executor.java:576) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.security.basic.EventHandler.invoke(EventHandler.java:154) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111) ~[org.springframework.orm.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108) ~[org.springframework.transaction.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:241) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) [org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202) [org.springframework.aop.jar:3.0.1.RELEASE-A] at com.sun.proxy.$Proxy71.doWork(Unknown Source) [na:na] at ome.services.util.Executor$Impl.execute(Executor.java:457) [server.jar:na] at ome.services.util.Executor$Impl.execute(Executor.java:401) [server.jar:na] at ome.services.sessions.SessionManagerImpl.executeCheckPasswordRW(SessionManagerImpl.java:974) [server.jar:na] at ome.services.sessions.SessionManagerImpl.executeCheckPassword(SessionManagerImpl.java:946) [server.jar:na] at ome.services.sessions.SessionManagerImpl.executePasswordCheck(SessionManagerImpl.java:921) [server.jar:na] at ome.services.blitz.fire.PermissionsVerifierI.checkPermissions(PermissionsVerifierI.java:135) [blitz.jar:na] at Glacier2._PermissionsVerifierDisp.___checkPermissions(_PermissionsVerifierDisp.java:118) [ice.jar:na] at Glacier2._PermissionsVerifierDisp.__dispatch(_PermissionsVerifierDisp.java:146) [ice.jar:na] at IceInternal.Incoming.invoke(Incoming.java:159) [ice.jar:na] at Ice.ConnectionI.invokeAll(ConnectionI.java:2357) [ice.jar:na] at Ice.ConnectionI.dispatch(ConnectionI.java:1208) [ice.jar:na] at Ice.ConnectionI.message(ConnectionI.java:1163) [ice.jar:na] at IceInternal.ThreadPool.run(ThreadPool.java:302) [ice.jar:na] at IceInternal.ThreadPool.access$300(ThreadPool.java:12) [ice.jar:na] at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:643) [ice.jar:na] at java.lang.Thread.run(Unknown Source) [na:1.8.0] 2014-04-04 13:19:38,730 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Excp: java.lang.ClassCastException: [B cannot be cast to java.lang.Comparable 2014-04-04 13:19:38,730 ERROR [ o.s.blitz.fire.PermissionsVerifierI] (l.Server-5) Exception thrown while checking password for:jason.kotecki ome.conditions.InternalException: Wrapped Exception: (java.lang.ClassCastException): [B cannot be cast to java.lang.Comparable at java.util.TreeMap.compare(Unknown Source) ~[na:1.8.0] at java.util.TreeMap.put(Unknown Source) ~[na:1.8.0] at java.util.TreeSet.add(Unknown Source) ~[na:1.8.0] at org.springframework.ldap.support.LdapUtils$CollectingAttributeValueCallbackHandler.handleAttributeValue(LdapUtils.java:291) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.support.LdapUtils.iterateAttributeValues(LdapUtils.java:268) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.support.LdapUtils.collectAttributeValues(LdapUtils.java:252) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.DirContextAdapter.getAttributeSortedStringSet(DirContextAdapter.java:785) ~[spring-ldap.jar:1.3.0.RELEASE] at ome.security.auth.AttributeSet.(AttributeSet.java:46) ~[server.jar:na] at ome.security.auth.PersonContextMapper.mapFromContext(PersonContextMapper.java:70) ~[server.jar:na] at org.springframework.ldap.core.ContextMapperCallbackHandler.getObjectFromNameClassPair(ContextMapperCallbackHandler.java:67) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.CollectingNameClassPairCallbackHandler.handleNameClassPair(CollectingNameClassPairCallbackHandler.java:50) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:297) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:259) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:606) ~[spring-ldap.jar:1.3.0.RELEASE] at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:524) ~[spring-ldap.jar:1.3.0.RELEASE] at ome.logic.LdapImpl.mapUserName(LdapImpl.java:201) ~[server.jar:na] at ome.logic.LdapImpl.findExperimenter(LdapImpl.java:183) ~[server.jar:na] at ome.logic.LdapImpl.createUserFromLdap(LdapImpl.java:388) ~[server.jar:na] at ome.security.auth.LdapPasswordProvider.checkPassword(LdapPasswordProvider.java:112) ~[server.jar:na] at ome.security.auth.PasswordProviders.checkPassword(PasswordProviders.java:42) ~[server.jar:na] at ome.logic.AdminImpl.checkPassword(AdminImpl.java:1203) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl$9.doWork(SessionManagerImpl.java:979) ~[server.jar:na] at sun.reflect.GeneratedMethodAccessor223.invoke(Unknown Source) ~[na:na] at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0] at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0] at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.services.util.Executor$Impl$Interceptor.invoke(Executor.java:576) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.security.basic.EventHandler.invoke(EventHandler.java:154) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.orm.hibernate3.HibernateInterceptor.invoke(HibernateInterceptor.java:111) ~[org.springframework.orm.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108) ~[org.springframework.transaction.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.tools.hibernate.ProxyCleanupFilter$Interceptor.invoke(ProxyCleanupFilter.java:241) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at ome.services.util.ServiceHandler.invoke(ServiceHandler.java:116) ~[server.jar:na] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202) ~[org.springframework.aop.jar:3.0.1.RELEASE-A] at com.sun.proxy.$Proxy71.doWork(Unknown Source) ~[na:na] at ome.services.util.Executor$Impl.execute(Executor.java:457) ~[server.jar:na] at ome.services.util.Executor$Impl.execute(Executor.java:401) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl.executeCheckPasswordRW(SessionManagerImpl.java:974) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl.executeCheckPassword(SessionManagerImpl.java:946) ~[server.jar:na] at ome.services.sessions.SessionManagerImpl.executePasswordCheck(SessionManagerImpl.java:921) ~[server.jar:na] at ome.services.blitz.fire.PermissionsVerifierI.checkPermissions(PermissionsVerifierI.java:135) ~[blitz.jar:na] at Glacier2._PermissionsVerifierDisp.___checkPermissions(_PermissionsVerifierDisp.java:118) [ice.jar:na] at Glacier2._PermissionsVerifierDisp.__dispatch(_PermissionsVerifierDisp.java:146) [ice.jar:na] at IceInternal.Incoming.invoke(Incoming.java:159) [ice.jar:na] at Ice.ConnectionI.invokeAll(ConnectionI.java:2357) [ice.jar:na] at Ice.ConnectionI.dispatch(ConnectionI.java:1208) [ice.jar:na] at Ice.ConnectionI.message(ConnectionI.java:1163) [ice.jar:na] at IceInternal.ThreadPool.run(ThreadPool.java:302) [ice.jar:na] at IceInternal.ThreadPool.access$300(ThreadPool.java:12) [ice.jar:na] at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:643) [ice.jar:na] at java.lang.Thread.run(Unknown Source) [na:1.8.0] 2014-04-04 13:19:38,824 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest) 2014-04-04 13:19:38,824 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Args: [null, InternalSF@5489205] 2014-04-04 13:19:38,824 INFO [ ome.security.basic.EventHandler] (l.Server-6) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:38,824 INFO [ org.perf4j.TimingLogger] (l.Server-6) start[1396631978824] time[5] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:38,824 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Rslt: true 2014-04-04 13:19:38,824 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession 2014-04-04 13:19:38,824 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Args: [null, InternalSF@5489205] 2014-04-04 13:19:38,839 INFO [ ome.security.basic.EventHandler] (l.Server-7) Auth: user=0,group=0,event=2764(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:38,855 INFO [ ome.security.basic.CurrentDetails] (l.Server-7) Adding log:INSERT,class ome.model.meta.Session,8168 2014-04-04 13:19:38,886 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1396631978824] time[54] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork] 2014-04-04 13:19:38,886 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Rslt: (ome.model.meta.Experimenter:Id_1, ome.model.meta.ExperimenterGroup:Id_2, (2), ... 4 more) 2014-04-04 13:19:38,886 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-7) Created session ServiceFactoryI(session-116068aa-c2cb-43a9-b3c1-0785e4b0573e/42e0960d-83b4-4cbb-bfe0-e28f3b22908d) for user guest (agent=OMERO.web) 2014-04-04 13:19:38,886 INFO [ omero.cmd.SessionI] (l.Server-1) Added servant to adapter: 42e0960d-83b4-4cbb-bfe0-e28f3b22908d/116068aa-c2cb-43a9-b3c1-0785e4b0573eomero.api.ISession(omero.api._ISessionTie@1ac0a1b3) 2014-04-04 13:19:38,902 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Meth: interface ome.api.ISession.getSession 2014-04-04 13:19:38,902 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Args: [42e0960d-83b4-4cbb-bfe0-e28f3b22908d] 2014-04-04 13:19:38,902 INFO [ org.perf4j.TimingLogger] (l.Server-0) start[1396631978902] time[0] tag[omero.call.success.ome.services.sessions.SessionBean.getSession] 2014-04-04 13:19:38,902 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Rslt: ome.model.meta.Session:Id_8168 2014-04-04 13:19:38,902 INFO [ omero.cmd.SessionI] (l.Server-3) Added servant to adapter: 42e0960d-83b4-4cbb-bfe0-e28f3b22908d/116068aa-c2cb-43a9-b3c1-0785e4b0573eomero.api.IConfig(omero.api._IConfigTie@1a897b34) 2014-04-04 13:19:38,902 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Meth: interface ome.api.IConfig.getConfigValue 2014-04-04 13:19:38,902 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [omero.version] 2014-04-04 13:19:38,917 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=1,group=2,event=2765(User),sess=42e0960d-83b4-4cbb-bfe0-e28f3b22908d 2014-04-04 13:19:38,933 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1396631978902] time[19] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue] 2014-04-04 13:19:38,933 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: 5.0.0-ice34-b19 2014-04-04 13:19:39,027 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(guest) 2014-04-04 13:19:39,027 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Args: [null, InternalSF@5489205] 2014-04-04 13:19:39,042 INFO [ ome.security.basic.EventHandler] (l.Server-5) Auth: user=0,group=0,event=null(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:39,042 INFO [ org.perf4j.TimingLogger] (l.Server-5) start[1396631979027] time[6] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork] 2014-04-04 13:19:39,042 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Rslt: true 2014-04-04 13:19:39,042 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession 2014-04-04 13:19:39,042 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Args: [null, InternalSF@5489205] 2014-04-04 13:19:39,058 INFO [ ome.security.basic.EventHandler] (l.Server-6) Auth: user=0,group=0,event=2766(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:39,073 INFO [ ome.security.basic.CurrentDetails] (l.Server-6) Adding log:INSERT,class ome.model.meta.Session,8169 2014-04-04 13:19:39,089 INFO [ org.perf4j.TimingLogger] (l.Server-6) start[1396631979042] time[49] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork] 2014-04-04 13:19:39,089 INFO [ ome.services.util.ServiceHandler] (l.Server-6) Rslt: (ome.model.meta.Experimenter:Id_1, ome.model.meta.ExperimenterGroup:Id_2, (2), ... 4 more) 2014-04-04 13:19:39,089 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-6) Created session ServiceFactoryI(session-de72782c-a484-4c95-9e87-7dc58fec8294/77ac3975-600b-4a43-8438-f337bc822259) for user guest (agent=OMERO.web) 2014-04-04 13:19:39,105 INFO [ omero.cmd.SessionI] (l.Server-9) Added servant to adapter: 77ac3975-600b-4a43-8438-f337bc822259/de72782c-a484-4c95-9e87-7dc58fec8294omero.api.ISession(omero.api._ISessionTie@19730333) 2014-04-04 13:19:39,105 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.ISession.getSession 2014-04-04 13:19:39,105 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [77ac3975-600b-4a43-8438-f337bc822259] 2014-04-04 13:19:39,105 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1396631979105] time[0] tag[omero.call.success.ome.services.sessions.SessionBean.getSession] 2014-04-04 13:19:39,105 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: ome.model.meta.Session:Id_8169 2014-04-04 13:19:39,120 INFO [ omero.cmd.SessionI] (l.Server-2) Added servant to adapter: 77ac3975-600b-4a43-8438-f337bc822259/de72782c-a484-4c95-9e87-7dc58fec8294omero.api.IConfig(omero.api._IConfigTie@19554086) 2014-04-04 13:19:39,120 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Meth: interface ome.api.IConfig.getConfigValue 2014-04-04 13:19:39,120 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Args: [omero.version] 2014-04-04 13:19:39,136 INFO [ ome.security.basic.EventHandler] (l.Server-3) Auth: user=1,group=2,event=2767(User),sess=77ac3975-600b-4a43-8438-f337bc822259 2014-04-04 13:19:39,136 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1396631979120] time[18] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue] 2014-04-04 13:19:39,136 INFO [ ome.services.util.ServiceHandler] (l.Server-3) Rslt: 5.0.0-ice34-b19 2014-04-04 13:19:43,535 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession 2014-04-04 13:19:43,535 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [null, InternalSF@5489205] 2014-04-04 13:19:43,551 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=0,group=0,event=2768(Sessions),sess=63fbe282-68b5-43ef-9b82-0b358ec111d6 2014-04-04 13:19:43,566 INFO [ ome.security.basic.CurrentDetails] (l.Server-4) Adding log:INSERT,class ome.model.meta.Session,8170 2014-04-04 13:19:43,582 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1396631983535] time[44] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork] 2014-04-04 13:19:43,582 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: (ome.model.meta.Experimenter:Id_0, ome.model.meta.ExperimenterGroup:Id_0, (0, 1), ... 4 more) 2014-04-04 13:19:43,582 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-4) Created session ServiceFactoryI(session-a2df444f-b466-4e7b-832f-c24e407afa07/abad415a-58f1-4918-abf8-828ec1b1e7de) for user root (agent=Python service) 2014-04-04 13:19:43,582 INFO [ ome.services.blitz.util.CheckAllJobs] (l.Server-9) Received 0 job(s) 2014-04-04 13:19:43,597 INFO [ omero.cmd.SessionI] (l.Server-8) Added servant to adapter: abad415a-58f1-4918-abf8-828ec1b1e7de/a2df444f-b466-4e7b-832f-c24e407afa07omero.grid.SharedResources(omero.grid._SharedResourcesTie@19e6d73c)