2009-10-26 16:42:55,970 INFO [ ome.services.blitz.Entry] ( main) Creating OMERO.blitz. Please wait... 2009-10-26 16:43:02,063 INFO [ng.ShutdownSafeEhcacheManagerFactoryBean] ( main) Initializing EHCache CacheManager 2009-10-26 16:43:11,421 INFO [ ome.services.fulltext.FullTextAnalyzer] ( main) Initialized FullTextAnalyzer 2009-10-26 16:43:26,092 INFO [ ome.system.PermissionsContext] ( main) Setting omero.security.default_permissions to rw---- 2009-10-26 16:43:26,764 INFO [ ome.services.fulltext.FullTextThread] ( main) Initializing Full-Text Indexer 2009-10-26 16:43:26,779 INFO [ ome.services.util.DBPatchCheck] ( main) Verified database patch: OMERO4.1__0 2009-10-26 16:43:26,779 INFO [ ome.services.util.ServerVersionCheck] ( main) ------------------------------------------------- 2009-10-26 16:43:26,779 INFO [ ome.services.util.ServerVersionCheck] ( main) OMERO Version: Beta-4.1.0-r5585-b12 Ready. 2009-10-26 16:43:26,779 INFO [ ome.services.util.ServerVersionCheck] ( main) ------------------------------------------------- 2009-10-26 16:43:28,123 WARN [ ome.system.UpgradeCheck] ( main) UPGRADE AVAILABLE: 2009-10-26 16:43:28,123 INFO [ ome.tools.hibernate.ExtendedMetadata] ( main) Calculating ExtendedMetadata... 2009-10-26 16:43:28,545 INFO [e.services.blitz.util.BlitzConfiguration] ( main) Initializing Ice.Communicator 2009-10-26 16:43:30,513 INFO [ ome.services.blitz.fire.Ring] ( main) Checking cluster 2009-10-26 16:43:30,545 INFO [ ome.services.blitz.fire.Registry] ( main) Found 0 cluster node(s) : [] 2009-10-26 16:43:30,545 INFO [ ome.services.blitz.fire.Ring] ( main) Got 0 cluster uuids : [] 2009-10-26 16:43:30,560 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.getManagerList 2009-10-26 16:43:30,560 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@e07e6b] 2009-10-26 16:43:30,638 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=null(Internal),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:43:30,716 INFO [ org.perf4j.TimingLogger] ( main) start[1256575410560] time[156] tag[omero.call.success] 2009-10-26 16:43:30,716 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: () 2009-10-26 16:43:30,716 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.addManager 2009-10-26 16:43:30,716 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@13e8c1c] 2009-10-26 16:43:31,123 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=51(Internal),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:43:31,154 INFO [ ome.security.basic.CurrentDetails] ( main) Adding log:INSERT,class ome.model.meta.Node,1 2009-10-26 16:43:31,201 INFO [ org.perf4j.TimingLogger] ( main) start[1256575410716] time[485] tag[omero.call.success] 2009-10-26 16:43:31,201 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: ome.model.meta.Node:Id_1 2009-10-26 16:43:31,373 INFO [ ome.services.blitz.fire.Registry] ( main) Added ClusterNode/bbdae40c-7af1-4b38-8e6a-48623622a056 to registry 2009-10-26 16:43:31,545 INFO [ ome.services.roi.GeomTool] ( main) Configured Shape.pg_geom 2009-10-26 16:43:31,545 INFO [ ome.system.PermissionsContext] ( main) Setting omero.security.default_permissions to rw---- 2009-10-26 16:43:31,545 INFO [me.services.blitz.repo.LegacyRepositoryI] ( main) Initializing repository in /OMERO/ 2009-10-26 16:43:31,591 INFO [ ome.services.roi.PopulateRoiJob] ( main) Script found: 63a410e344cc22b2fcae09951137a5b59fb8baae 2009-10-26 16:43:31,591 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.roi.PopulateRoiJob.populateroi.py 2009-10-26 16:43:31,591 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@1126d91] 2009-10-26 16:43:31,638 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=52(Internal),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:43:31,810 INFO [ ome.services.roi.PopulateRoiJob] ( main) Creating script 2009-10-26 16:43:31,857 INFO [ ome.security.basic.CurrentDetails] ( main) Adding log:INSERT,class ome.model.core.OriginalFile,1 2009-10-26 16:43:31,888 INFO [ org.perf4j.TimingLogger] ( main) start[1256575411591] time[297] tag[omero.call.success] 2009-10-26 16:43:31,888 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: ome.model.core.OriginalFile:Id_1 2009-10-26 16:43:31,904 INFO [ ome.services.scripts.MakeMovieJob] ( main) Script found: 5f408441299c22320dc56b8d12829d9e9fa814a2 2009-10-26 16:43:31,904 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.scripts.MakeMovieJob.makemovie.py 2009-10-26 16:43:31,904 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@6f0962] 2009-10-26 16:43:31,920 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=53(Internal),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:43:31,935 INFO [ ome.services.scripts.MakeMovieJob] ( main) Creating script 2009-10-26 16:43:31,935 INFO [ ome.security.basic.CurrentDetails] ( main) Adding log:INSERT,class ome.model.core.OriginalFile,2 2009-10-26 16:43:31,966 INFO [ org.perf4j.TimingLogger] ( main) start[1256575411904] time[62] tag[omero.call.success] 2009-10-26 16:43:31,966 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: ome.model.core.OriginalFile:Id_2 2009-10-26 16:43:31,982 INFO [ ome.services.blitz.Entry] ( main) OMERO.blitz now accepting connections. 2009-10-26 16:43:31,998 INFO [ ome.services.util.ServiceHandler] (r_Worker-7) Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.takeover 2009-10-26 16:43:32,013 INFO [ ome.services.util.ServiceHandler] (r_Worker-7) Args: [null, ome.tools.spring.InternalServiceFactory@d8f317] 2009-10-26 16:43:32,091 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Executor.doWork -- ome.services.roi.GeomTool.backgroundSynchronization 2009-10-26 16:43:32,091 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Args: [null, ome.tools.spring.InternalServiceFactory@616364] 2009-10-26 16:43:32,091 INFO [ ome.security.basic.EventHandler] (r_Worker-7) Auth: user=0,group=0,event=54(Internal),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:43:32,091 INFO [ ome.services.blitz.repo.FileMaker] (r_Worker-7) Creating \OMERO\.omero\repository\c72039e1-0492-4ad7-b0d9-4499231e0f6f 2009-10-26 16:43:32,185 INFO [ ome.security.basic.CurrentDetails] (r_Worker-7) Adding log:INSERT,class ome.model.core.OriginalFile,3 2009-10-26 16:43:32,185 INFO [me.services.blitz.repo.LegacyRepositoryI] (r_Worker-7) Registered new repository: fd38cd69-35e5-4d37-bfe7-8b674bd53dfc 2009-10-26 16:43:32,263 INFO [ ome.security.basic.EventHandler] (r_Worker-9) Auth: user=0,group=0,event=55(Internal),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:43:32,294 INFO [ org.perf4j.TimingLogger] (r_Worker-9) start[1256575412091] time[203] tag[omero.call.success] 2009-10-26 16:43:32,294 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Rslt: 0 2009-10-26 16:43:32,435 INFO [ ome.services.blitz.fire.Registry] (r_Worker-7) Added InternalRepository-fd38cd69-35e5-4d37-bfe7-8b674bd53dfc to registry 2009-10-26 16:43:32,607 INFO [ ome.services.blitz.fire.Registry] (r_Worker-7) Added PublicRepository-fd38cd69-35e5-4d37-bfe7-8b674bd53dfc to registry 2009-10-26 16:43:32,607 INFO [me.services.blitz.repo.LegacyRepositoryI] (r_Worker-7) Repository now active 2009-10-26 16:43:32,732 INFO [ org.perf4j.TimingLogger] (r_Worker-7) start[1256575412013] time[719] tag[omero.call.success] 2009-10-26 16:43:32,732 INFO [ ome.services.util.ServiceHandler] (r_Worker-7) Rslt: null 2009-10-26 16:43:33,607 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession 2009-10-26 16:43:33,607 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Args: [null, ome.tools.spring.InternalServiceFactory@96d92e] 2009-10-26 16:43:33,638 INFO [ ome.security.basic.EventHandler] (l.Server-8) Auth: user=0,group=0,event=56(Sessions),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:43:33,701 INFO [ ome.security.basic.CurrentDetails] (l.Server-8) Adding log:INSERT,class ome.model.meta.Session,54 2009-10-26 16:43:33,841 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1256575413607] time[234] tag[omero.call.success] 2009-10-26 16:43:33,841 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: (ome.model.meta.Experimenter:Id_0, ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more) 2009-10-26 16:43:33,841 INFO [ ome.services.licenses.Store] (l.Server-8) Acquired license from example license store. 2009-10-26 16:43:33,841 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-8) Created session b557037d-59e6-4fe7-a111-81bf29ac4285 for user root 2009-10-26 16:43:33,857 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-1) Added servant to adapter: b557037d-59e6-4fe7-a111-81bf29ac4285/omero.api.IConfig(omero.api._IConfigTie@ec7c94) 2009-10-26 16:43:34,154 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Meth: interface ome.api.IConfig.getConfigValue 2009-10-26 16:43:34,154 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Args: [omero.data.dir] 2009-10-26 16:43:34,185 INFO [ ome.security.basic.EventHandler] (l.Server-1) Auth: user=0,group=0,event=57(User),sess=b557037d-59e6-4fe7-a111-81bf29ac4285 2009-10-26 16:43:34,201 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1256575414154] time[47] tag[omero.call.success] 2009-10-26 16:43:34,201 INFO [ ome.services.util.ServiceHandler] (l.Server-1) Rslt: /OMERO/ 2009-10-26 16:43:34,201 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-3) Closing ome.services.blitz.impl.ServiceFactoryI@6af2fb session 2009-10-26 16:43:34,201 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-3) Unregistered servant:b557037d-59e6-4fe7-a111-81bf29ac4285/omero.api.IConfig(omero.api._IConfigTie@ec7c94) 2009-10-26 16:43:34,201 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-3) Removed servant from adapter: omero.api.IConfig 2009-10-26 16:43:34,201 INFO [ome.services.sessions.state.SessionCache] (l.Server-3) Destroying session b557037d-59e6-4fe7-a111-81bf29ac4285 due to : Remove session called 2009-10-26 16:43:34,201 INFO [ org.perf4j.TimingLogger] (l.Server-3) start[1256575413841] time[360] tag[omero.session] 2009-10-26 16:43:34,232 INFO [ ome.services.licenses.Store] (l.Server-3) Released license to example license store. 2009-10-26 16:45:00,021 INFO [ ome.services.blitz.fire.SessionManagerI] (r_Worker-0) Performing requestHeartbeats 2009-10-26 16:48:00,025 INFO [ ome.services.blitz.fire.SessionManagerI] (r_Worker-9) Performing requestHeartbeats 2009-10-26 16:48:35,335 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPassword 2009-10-26 16:48:35,335 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Args: [null, ome.tools.spring.InternalServiceFactory@18ab963] 2009-10-26 16:48:35,460 INFO [ ome.security.basic.EventHandler] (l.Server-5) Auth: user=0,group=0,event=null(Sessions),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:48:35,460 INFO [ org.perf4j.TimingLogger] (l.Server-5) start[1256575715335] time[125] tag[omero.call.success] 2009-10-26 16:48:35,460 INFO [ ome.services.util.ServiceHandler] (l.Server-5) Rslt: false 2009-10-26 16:48:45,851 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPassword 2009-10-26 16:48:45,851 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Args: [null, ome.tools.spring.InternalServiceFactory@26f144] 2009-10-26 16:48:45,851 INFO [ ome.security.basic.EventHandler] (l.Server-8) Auth: user=0,group=0,event=null(Sessions),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:48:45,851 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1256575725851] time[0] tag[omero.call.success] 2009-10-26 16:48:45,851 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: false 2009-10-26 16:49:31,067 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPassword 2009-10-26 16:49:31,067 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Args: [null, ome.tools.spring.InternalServiceFactory@177357d] 2009-10-26 16:49:31,067 INFO [ ome.security.basic.EventHandler] (l.Server-0) Auth: user=0,group=0,event=null(Sessions),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:49:31,067 INFO [ org.perf4j.TimingLogger] (l.Server-0) start[1256575771067] time[0] tag[omero.call.success] 2009-10-26 16:49:31,067 INFO [ ome.services.util.ServiceHandler] (l.Server-0) Rslt: false 2009-10-26 16:51:00,031 INFO [ ome.services.blitz.fire.SessionManagerI] (r_Worker-2) Performing requestHeartbeats 2009-10-26 16:53:08,636 INFO [ ome.services.blitz.Entry] (RM handler) TERM: Shutdown requested. 2009-10-26 16:53:08,636 INFO [ ome.services.blitz.Entry] (RM handler) Calling close on context OMERO.blitz 2009-10-26 16:53:08,636 INFO [me.services.blitz.repo.LegacyRepositoryI] (RM handler) Releasing /OMERO/ 2009-10-26 16:53:08,871 INFO [ ome.services.blitz.fire.Registry] (RM handler) Removed ClusterNode/bbdae40c-7af1-4b38-8e6a-48623622a056 from registry 2009-10-26 16:53:08,933 INFO [ ome.services.util.ServiceHandler] (RM handler) Executor.doWork -- ome.services.blitz.fire.Ring.executeUpdate - set closed = now() 2009-10-26 16:53:08,933 INFO [ ome.services.util.ServiceHandler] (RM handler) Args: [null, ome.tools.spring.InternalServiceFactory@1607fa8] 2009-10-26 16:53:09,449 INFO [ ome.security.basic.EventHandler] (RM handler) Auth: user=0,group=0,event=58(Internal),sess=bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:53:09,480 INFO [ org.perf4j.TimingLogger] (RM handler) start[1256575988933] time[547] tag[omero.call.success] 2009-10-26 16:53:09,480 INFO [ ome.services.util.ServiceHandler] (RM handler) Rslt: 0 2009-10-26 16:53:09,480 INFO [ ome.services.blitz.fire.Ring] (RM handler) Removed 0 entries for bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 16:53:09,480 INFO [ ome.services.blitz.fire.Ring] (RM handler) Disconnected from OMERO.cluster 2009-10-26 16:53:09,480 INFO [ ome.services.blitz.fire.Registry] (RM handler) Found 0 cluster node(s) : [] 2009-10-26 16:53:09,480 INFO [e.services.blitz.util.BlitzConfiguration] (RM handler) Shutting down Ice.Communicator 2009-10-26 16:53:09,511 INFO [ ome.services.util.ServerVersionCheck] (RM handler) ------------------------------------------------- 2009-10-26 16:53:09,511 INFO [ ome.services.util.ServerVersionCheck] (RM handler) Stopping OMERO... 2009-10-26 16:53:09,511 INFO [ ome.services.util.ServerVersionCheck] (RM handler) ------------------------------------------------- 2009-10-26 16:53:09,511 INFO [ ome.services.fulltext.FullTextThread] (RM handler) Shutting down Full-Text Indexer 2009-10-26 16:53:09,511 INFO [.services.scheduler.SchedulerFactoryBean] (RM handler) Shutting down Quartz Scheduler 2009-10-26 16:53:09,511 INFO [ng.ShutdownSafeEhcacheManagerFactoryBean] (RM handler) Shutting down EHCache CacheManager 2009-10-26 16:53:09,511 INFO [ ome.services.blitz.Entry] (RM handler) Finished shutdown. 2009-10-26 16:53:11,933 INFO [ ome.services.blitz.Entry] ( main) Creating OMERO.blitz. Please wait... 2009-10-26 16:53:15,120 INFO [ng.ShutdownSafeEhcacheManagerFactoryBean] ( main) Initializing EHCache CacheManager 2009-10-26 16:53:22,788 INFO [ ome.services.fulltext.FullTextAnalyzer] ( main) Initialized FullTextAnalyzer 2009-10-26 16:53:23,959 INFO [ ome.services.blitz.Entry] (RM handler) TERM: Shutdown requested. 2009-10-26 16:58:07,472 INFO [ ome.services.blitz.Entry] ( main) Creating OMERO.blitz. Please wait... 2009-10-26 16:59:04,249 INFO [ng.ShutdownSafeEhcacheManagerFactoryBean] ( main) Initializing EHCache CacheManager 2009-10-26 17:00:20,666 INFO [ ome.services.fulltext.FullTextAnalyzer] ( main) Initialized FullTextAnalyzer 2009-10-26 17:01:01,679 INFO [ ome.system.PermissionsContext] ( main) Setting omero.security.default_permissions to rw---- 2009-10-26 17:01:04,339 INFO [ ome.services.fulltext.FullTextThread] ( main) Initializing Full-Text Indexer 2009-10-26 17:01:05,951 INFO [ ome.services.util.DBPatchCheck] ( main) Verified database patch: OMERO4.1__0 2009-10-26 17:01:05,951 INFO [ ome.services.util.ServerVersionCheck] ( main) ------------------------------------------------- 2009-10-26 17:01:05,951 INFO [ ome.services.util.ServerVersionCheck] ( main) OMERO Version: Beta-4.1.0-r5585-b12 Ready. 2009-10-26 17:01:05,951 INFO [ ome.services.util.ServerVersionCheck] ( main) ------------------------------------------------- 2009-10-26 17:01:15,981 ERROR [ ome.system.UpgradeCheck] ( main) Error reading from url: http://upgrade.openmicroscopy.org.uk?version=Beta-4.1.0-r5585-b12;os.name=Windows+XP;os.arch=x86;os.version=5.1;java.runtime.version=1.6.0_16-b01;java.vm.vendor=Sun+Microsystems+Inc. "connect timed out" 2009-10-26 17:01:15,981 INFO [ ome.tools.hibernate.ExtendedMetadata] ( main) Calculating ExtendedMetadata... 2009-10-26 17:01:20,676 INFO [e.services.blitz.util.BlitzConfiguration] ( main) Initializing Ice.Communicator 2009-10-26 17:01:25,260 INFO [ ome.services.blitz.fire.Ring] ( main) Checking cluster 2009-10-26 17:01:25,401 INFO [ ome.services.blitz.fire.Registry] ( main) Found 0 cluster node(s) : [] 2009-10-26 17:01:25,401 INFO [ ome.services.blitz.fire.Ring] ( main) Got 0 cluster uuids : [] 2009-10-26 17:01:25,401 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.getManagerList 2009-10-26 17:01:25,401 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@677ea2] 2009-10-26 17:01:25,464 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=null(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:25,605 INFO [ org.perf4j.TimingLogger] ( main) start[1256576485401] time[204] tag[omero.call.success] 2009-10-26 17:01:25,605 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: (bbdae40c-7af1-4b38-8e6a-48623622a056) 2009-10-26 17:01:25,605 INFO [ ome.services.blitz.fire.Ring] ( main) Purging node: bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 17:01:26,246 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.executeUpdate - set closed = now() 2009-10-26 17:01:26,246 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@1e2254a] 2009-10-26 17:01:26,669 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=251(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:26,794 INFO [ org.perf4j.TimingLogger] ( main) start[1256576486246] time[548] tag[omero.call.success] 2009-10-26 17:01:26,794 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: 0 2009-10-26 17:01:26,794 INFO [ ome.services.blitz.fire.Ring] ( main) Removed 0 entries with value bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 17:01:26,794 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.setManagerDown 2009-10-26 17:01:26,794 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@ba2b6b] 2009-10-26 17:01:26,825 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=252(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:26,841 INFO [ org.perf4j.TimingLogger] ( main) start[1256576486794] time[47] tag[omero.call.success] 2009-10-26 17:01:26,841 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: 1 2009-10-26 17:01:26,841 INFO [ ome.services.blitz.fire.Ring] ( main) Removed manager: bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 17:01:26,841 INFO [ ome.services.blitz.fire.Ring] ( main) handleRingShutdown: bbdae40c-7af1-4b38-8e6a-48623622a056 2009-10-26 17:01:26,841 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.blitz.fire.Ring.addManager 2009-10-26 17:01:26,841 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@139ebdb] 2009-10-26 17:01:26,872 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=253(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:26,888 INFO [ ome.security.basic.CurrentDetails] ( main) Adding log:INSERT,class ome.model.meta.Node,51 2009-10-26 17:01:26,935 INFO [ org.perf4j.TimingLogger] ( main) start[1256576486841] time[94] tag[omero.call.success] 2009-10-26 17:01:26,950 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: ome.model.meta.Node:Id_51 2009-10-26 17:01:27,107 INFO [ ome.services.blitz.fire.Registry] ( main) Added ClusterNode/c8326fb4-d84e-4411-9f2d-36fd2b047319 to registry 2009-10-26 17:01:27,623 INFO [ ome.system.PermissionsContext] ( main) Setting omero.security.default_permissions to rw---- 2009-10-26 17:01:27,764 INFO [me.services.blitz.repo.LegacyRepositoryI] ( main) Initializing repository in /OMERO/ 2009-10-26 17:01:28,938 INFO [ ome.services.roi.PopulateRoiJob] ( main) Script found: 63a410e344cc22b2fcae09951137a5b59fb8baae 2009-10-26 17:01:28,938 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.roi.PopulateRoiJob.populateroi.py 2009-10-26 17:01:28,938 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@8e68bd] 2009-10-26 17:01:28,969 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=254(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:29,720 INFO [ org.perf4j.TimingLogger] ( main) start[1256576488938] time[782] tag[omero.call.success] 2009-10-26 17:01:29,720 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: ome.model.core.OriginalFile:Id_1 2009-10-26 17:01:30,049 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession 2009-10-26 17:01:30,049 INFO [ ome.services.util.ServiceHandler] (l.Server-7) Args: [null, ome.tools.spring.InternalServiceFactory@213339] 2009-10-26 17:01:30,158 INFO [ ome.security.basic.EventHandler] (l.Server-7) Auth: user=0,group=0,event=255(Sessions),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:30,377 INFO [ ome.services.scripts.MakeMovieJob] ( main) Script found: 5f408441299c22320dc56b8d12829d9e9fa814a2 2009-10-26 17:01:30,377 INFO [ ome.services.util.ServiceHandler] ( main) Executor.doWork -- ome.services.scripts.MakeMovieJob.makemovie.py 2009-10-26 17:01:30,377 INFO [ ome.services.util.ServiceHandler] ( main) Args: [null, ome.tools.spring.InternalServiceFactory@184246a] 2009-10-26 17:01:30,471 INFO [ ome.security.basic.EventHandler] ( main) Auth: user=0,group=0,event=256(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:30,628 INFO [ org.perf4j.TimingLogger] ( main) start[1256576490377] time[251] tag[omero.call.success] 2009-10-26 17:01:30,628 INFO [ ome.services.util.ServiceHandler] ( main) Rslt: ome.model.core.OriginalFile:Id_2 2009-10-26 17:01:30,659 INFO [ ome.security.basic.CurrentDetails] (l.Server-7) Adding log:INSERT,class ome.model.meta.Session,156 2009-10-26 17:01:30,675 INFO [ ome.services.blitz.Entry] ( main) OMERO.blitz now accepting connections. 2009-10-26 17:01:30,721 INFO [ org.perf4j.TimingLogger] (l.Server-7) start[1256576490049] time[672] tag[omero.call.success] 2009-10-26 17:01:30,737 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) 2009-10-26 17:01:30,737 INFO [ ome.services.licenses.Store] (l.Server-7) Acquired license from example license store. 2009-10-26 17:01:30,753 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.takeover 2009-10-26 17:01:30,753 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Args: [null, ome.tools.spring.InternalServiceFactory@1428ffa] 2009-10-26 17:01:30,753 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-7) Created session e991da93-e842-4e18-b3bc-017f04fbcff1 for user root 2009-10-26 17:01:30,800 INFO [ ome.security.basic.EventHandler] (r_Worker-9) Auth: user=0,group=0,event=257(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:30,847 INFO [ ome.services.util.ServiceHandler] (r_Worker-0) Executor.doWork -- ome.services.roi.GeomTool.backgroundSynchronization 2009-10-26 17:01:30,847 INFO [ ome.services.util.ServiceHandler] (r_Worker-0) Args: [null, ome.tools.spring.InternalServiceFactory@492ff1] 2009-10-26 17:01:31,160 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession 2009-10-26 17:01:31,160 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Args: [null, ome.tools.spring.InternalServiceFactory@1267610] 2009-10-26 17:01:31,207 INFO [ ome.security.basic.EventHandler] (r_Worker-0) Auth: user=0,group=0,event=258(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:31,316 INFO [ org.perf4j.TimingLogger] (r_Worker-0) start[1256576490847] time[469] tag[omero.call.success] 2009-10-26 17:01:31,316 INFO [ ome.services.util.ServiceHandler] (r_Worker-0) Rslt: 0 2009-10-26 17:01:31,457 INFO [ ome.security.basic.EventHandler] (l.Server-8) Auth: user=0,group=0,event=259(Sessions),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:01:31,504 INFO [ ome.security.basic.CurrentDetails] (l.Server-8) Adding log:INSERT,class ome.model.meta.Session,157 2009-10-26 17:01:31,598 INFO [ org.perf4j.TimingLogger] (l.Server-8) start[1256576491160] time[438] tag[omero.call.success] 2009-10-26 17:01:31,598 INFO [ ome.services.util.ServiceHandler] (l.Server-8) Rslt: (ome.model.meta.Experimenter:Id_0, ome.model.meta.ExperimenterGroup:Id_0, [0, 1], ... 4 more) 2009-10-26 17:01:31,598 INFO [ ome.services.licenses.Store] (l.Server-8) Acquired license from example license store. 2009-10-26 17:01:31,598 INFO [ ome.services.blitz.fire.SessionManagerI] (l.Server-8) Created session ed2286f2-618a-4c4b-816f-dff5730975ab for user root 2009-10-26 17:01:32,005 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-5) Added servant to adapter: ed2286f2-618a-4c4b-816f-dff5730975ab/omero.api.IConfig(omero.api._IConfigTie@c67f69) 2009-10-26 17:01:32,036 INFO [me.services.blitz.repo.LegacyRepositoryI] (r_Worker-9) Opened repository: fd38cd69-35e5-4d37-bfe7-8b674bd53dfc 2009-10-26 17:01:32,521 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Meth: interface ome.api.IConfig.getConfigValue 2009-10-26 17:01:32,521 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Args: [omero.data.dir] 2009-10-26 17:01:32,552 INFO [ ome.security.basic.EventHandler] (l.Server-4) Auth: user=0,group=0,event=260(User),sess=ed2286f2-618a-4c4b-816f-dff5730975ab 2009-10-26 17:01:32,552 INFO [ org.perf4j.TimingLogger] (l.Server-4) start[1256576492521] time[31] tag[omero.call.success] 2009-10-26 17:01:32,552 INFO [ ome.services.util.ServiceHandler] (l.Server-4) Rslt: /OMERO/ 2009-10-26 17:01:32,599 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-0) Closing ome.services.blitz.impl.ServiceFactoryI@315e08 session 2009-10-26 17:01:32,662 INFO [ ome.services.blitz.fire.Registry] (r_Worker-9) Updated InternalRepository-fd38cd69-35e5-4d37-bfe7-8b674bd53dfc in registry 2009-10-26 17:01:32,850 INFO [ ome.services.blitz.fire.Registry] (r_Worker-9) Updated PublicRepository-fd38cd69-35e5-4d37-bfe7-8b674bd53dfc in registry 2009-10-26 17:01:32,865 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-0) Unregistered servant:ed2286f2-618a-4c4b-816f-dff5730975ab/omero.api.IConfig(omero.api._IConfigTie@c67f69) 2009-10-26 17:01:32,865 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-0) Removed servant from adapter: omero.api.IConfig 2009-10-26 17:01:32,865 INFO [ome.services.sessions.state.SessionCache] (l.Server-0) Destroying session ed2286f2-618a-4c4b-816f-dff5730975ab due to : Remove session called 2009-10-26 17:01:32,865 INFO [ org.perf4j.TimingLogger] (l.Server-0) start[1256576491598] time[1267] tag[omero.session] 2009-10-26 17:01:32,912 INFO [ ome.services.licenses.Store] (l.Server-0) Released license to example license store. 2009-10-26 17:01:32,959 INFO [me.services.blitz.repo.LegacyRepositoryI] (r_Worker-9) Repository now active 2009-10-26 17:01:32,959 INFO [ org.perf4j.TimingLogger] (r_Worker-9) start[1256576490753] time[2206] tag[omero.call.success] 2009-10-26 17:01:32,959 INFO [ ome.services.util.ServiceHandler] (r_Worker-9) Rslt: null 2009-10-26 17:01:32,959 WARN [ ome.services.util.ServiceHandler] (r_Worker-9) Method interface ome.services.util.Executor$Work.doWork invocation took 2206 2009-10-26 17:02:09,716 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-1) Keep alive: e991da93-e842-4e18-b3bc-017f04fbcff1 2009-10-26 17:03:00,024 INFO [ ome.services.blitz.fire.SessionManagerI] (r_Worker-4) Performing requestHeartbeats 2009-10-26 17:03:04,186 INFO [ ome.services.blitz.Entry] (RM handler) TERM: Shutdown requested. 2009-10-26 17:03:04,186 INFO [ ome.services.blitz.Entry] (RM handler) Calling close on context OMERO.blitz 2009-10-26 17:03:04,186 INFO [me.services.blitz.repo.LegacyRepositoryI] (RM handler) Releasing /OMERO/ 2009-10-26 17:03:04,359 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-1) Keep alive: e991da93-e842-4e18-b3bc-017f04fbcff1 2009-10-26 17:03:04,359 INFO [ ome.services.blitz.impl.ServiceFactoryI] (l.Server-1) Closing ome.services.blitz.impl.ServiceFactoryI@d03350 session 2009-10-26 17:03:04,359 INFO [ome.services.sessions.state.SessionCache] (l.Server-1) Destroying session e991da93-e842-4e18-b3bc-017f04fbcff1 due to : Remove session called 2009-10-26 17:03:04,359 INFO [ org.perf4j.TimingLogger] (l.Server-1) start[1256576490737] time[93622] tag[omero.session] 2009-10-26 17:03:04,374 INFO [ ome.services.licenses.Store] (l.Server-1) Released license to example license store. 2009-10-26 17:03:04,562 INFO [ ome.services.blitz.fire.Registry] (RM handler) Removed ClusterNode/c8326fb4-d84e-4411-9f2d-36fd2b047319 from registry 2009-10-26 17:03:04,562 INFO [ ome.services.util.ServiceHandler] (RM handler) Executor.doWork -- ome.services.blitz.fire.Ring.executeUpdate - set closed = now() 2009-10-26 17:03:04,562 INFO [ ome.services.util.ServiceHandler] (RM handler) Args: [null, ome.tools.spring.InternalServiceFactory@ea8847] 2009-10-26 17:03:04,609 INFO [ ome.security.basic.EventHandler] (RM handler) Auth: user=0,group=0,event=261(Internal),sess=c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:03:04,625 INFO [ org.perf4j.TimingLogger] (RM handler) start[1256576584562] time[63] tag[omero.call.success] 2009-10-26 17:03:04,625 INFO [ ome.services.util.ServiceHandler] (RM handler) Rslt: 0 2009-10-26 17:03:04,625 INFO [ ome.services.blitz.fire.Ring] (RM handler) Removed 0 entries for c8326fb4-d84e-4411-9f2d-36fd2b047319 2009-10-26 17:03:04,625 INFO [ ome.services.blitz.fire.Ring] (RM handler) Disconnected from OMERO.cluster 2009-10-26 17:03:04,625 INFO [ ome.services.blitz.fire.Registry] (RM handler) Found 0 cluster node(s) : [] 2009-10-26 17:03:04,625 INFO [e.services.blitz.util.BlitzConfiguration] (RM handler) Shutting down Ice.Communicator 2009-10-26 17:03:04,656 INFO [ ome.services.util.ServerVersionCheck] (RM handler) ------------------------------------------------- 2009-10-26 17:03:04,656 INFO [ ome.services.util.ServerVersionCheck] (RM handler) Stopping OMERO... 2009-10-26 17:03:04,656 INFO [ ome.services.util.ServerVersionCheck] (RM handler) ------------------------------------------------- 2009-10-26 17:03:04,656 INFO [ ome.services.fulltext.FullTextThread] (RM handler) Shutting down Full-Text Indexer 2009-10-26 17:03:04,656 INFO [.services.scheduler.SchedulerFactoryBean] (RM handler) Shutting down Quartz Scheduler 2009-10-26 17:03:04,656 INFO [ng.ShutdownSafeEhcacheManagerFactoryBean] (RM handler) Shutting down EHCache CacheManager 2009-10-26 17:03:04,656 INFO [ ome.services.blitz.Entry] (RM handler) Finished shutdown. 2009-10-26 17:03:07,222 INFO [ ome.services.blitz.Entry] ( main) Creating OMERO.blitz. Please wait... 2009-10-26 17:03:11,134 INFO [ng.ShutdownSafeEhcacheManagerFactoryBean] ( main) Initializing EHCache CacheManager 2009-10-26 17:03:11,744 INFO [ ome.services.blitz.Entry] (RM handler) TERM: Shutdown requested.