We're Hiring!

DB Connection error with Postgresql 9.1

Having a problem deploying OMERO? Please ask new questions at https://forum.image.sc/tags/omero
Please note:
Historical discussions about OMERO. Please look for and ask new questions at https://forum.image.sc/tags/omero

The OMERO.server installation documentation begins here and you can find OMERO.web deployment documentation here.

DB Connection error with Postgresql 9.1

Postby pkMyt1 » Sat Sep 29, 2012 2:26 am

I'm at my wits end tying to track down this problem. I upgraded Postgres from 8.3 to 9.1 to solve the log issue reported on this forum. While at it I decided to upgrade from Omero 4.4 to 4.4.4. I have postgres running and when I look at the tables all my data is there but Omero will not connect. Gives up and exits after about 5 minutes. If I make a empty db it will connect so the problem appears to be with the old database. I tried to attach the Blitz log but was not successful. I can roll back to postgres8.3 but the growth of the log files was becoming a problem. I've also included the output of the diagnostics run. Any help will be greatly appreciated.

================================================================================
OMERO Diagnostics 4.4.4-ice34-b3099
================================================================================

Commands: java -version 1.7.0 (/usr/bin/java)
Commands: python -V 2.4.3 (/usr/bin/python)
Commands: icegridnode --version 3.4.2 (/usr/bin/icegridnode)
Commands: icegridadmin --version 3.4.2 (/usr/bin/icegridadmin)
Commands: psql --version 9.1.6 (/usr/bin/psql)

Server: icegridnode not started

Log dir: /usr/omero/var/log exists

Log files: .Blitz-0.log.swp 16.0 KB
Log files: Blitz-0.log 180.0 KB errors=8 warnings=9
Log files: DropBox.log 6.0 KB errors=7 warnings=2
Log files: FileServer.log 1.0 KB
Log files: Indexer-0.log 179.0 KB errors=7 warnings=0
Log files: MonitorServer.log 3.0 KB errors=5 warnings=0
Log files: OMEROweb.log n/a
Log files: PixelData-0.log 55.0 KB errors=7 warnings=0
Log files: Processor-0.log 33.0 KB errors=8 warnings=18
Log files: Tables-0.log n/a
Log files: TestDropBox.log n/a
Log files: master.err 3.0 KB
Log files: master.out 0.0 KB
Log files: Total size 0.48 MB

Parsing Blitz-0.log:[line:117] => Server restarted <=
Parsing Blitz-0.log:[line:348] => Server restarted <=
Parsing Blitz-0.log:[line:698] => Server restarted <=
Parsing Blitz-0.log:[line:1028] Your database configuration is invalid
Parsing Blitz-0.log:[line:1036] Your database configuration is invalid
Parsing Blitz-0.log:[line:1055] Your database configuration is invalid
Parsing Blitz-0.log:[line:1070] Your database configuration is invalid
Parsing Blitz-0.log:[line:1078] Your database configuration is invalid
Parsing Blitz-0.log:[line:1101] Your database configuration is invalid

Environment:OMERO_HOME=(unset)
Environment:OMERO_NODE=(unset)
Environment:OMERO_MASTER=(unset)
Environment:PATH=/usr/kerberos/bin:/usr/local/bin:/bin:/usr/bin:/home/dennis/bin
Environment:ICE_HOME=(unset)
Environment:LD_LIBRARY_PATH=(unset)
Environment:DYLD_LIBRARY_PATH=(unset)

OMERO data dir: '/OMERO' Exists? True Is writable? True
OMERO.web status... [NOT STARTED]
pkMyt1
 
Posts: 12
Joined: Sat Sep 29, 2012 2:07 am

Re: DB Connection error with Postgresql 9.1

Postby pkMyt1 » Sat Sep 29, 2012 5:03 pm

Near as I can tell is something in the omero db must have gotten corrupted. I was able to restore using an old backup successfully. I tried merging the db tables from the one that doesn't work with the one that does but it breaks omero. So a new question. I have orphaned images and other files now in the /OMERO folder. Is this a problem for omero and is there a way to clean out these unlinked files? We are a small lab so this is only about 100 images.
pkMyt1
 
Posts: 12
Joined: Sat Sep 29, 2012 2:07 am

Re: DB Connection error with Postgresql 9.1

Postby cxallan » Mon Oct 01, 2012 8:35 am

Could you paste the exact lines you have in Blitz-0.log for the connection errors? OMERO will not overwrite files in the binary repository as a safety precaution. You will have to go in and delete them if you want to continue with the database you have restored.
cxallan
Site Admin
 
Posts: 509
Joined: Fri May 01, 2009 8:07 am

Re: DB Connection error with Postgresql 9.1

Postby pkMyt1 » Mon Oct 01, 2012 1:37 pm

Thank you for looking into this. Here is the output from the Blitz log containing the errors. While I would like to know if this is something I did (a not unlikely cause), I have us running again from a backup. I did need to run cleanse as the orphaned files appeared to be causing some issues.
Code: Select all
2012-09-28 20:25:59,844 INFO  [                 ome.system.UpgradeCheck] (      main) no update needed
2012-09-28 20:25:59,848 INFO  [    ome.tools.hibernate.ExtendedMetadata] (      main) Calculating ExtendedMetadata...
2012-09-28 20:26:00,017 INFO  [.services.scheduler.SchedulerFactoryBean] (      main) Starting Quartz Scheduler now
2012-09-28 20:26:00,027 INFO  [        ome.services.util.ServiceHandler] (      main)  Executor.doWork -- ome.services.util.NamespaceCheck.namespaceCheck
2012-09-28 20:26:00,027 INFO  [        ome.services.util.ServiceHandler] (      main)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:00,989 INFO  [         ome.security.basic.EventHandler] (      main)  Auth:   user=0,group=0,event=16867(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:01,744 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348878360027] time[1717] tag[omero.call.success.ome.services.util.NamespaceCheck$1.doWork]
2012-09-28 20:26:01,744 INFO  [        ome.services.util.ServiceHandler] (      main)  Rslt:   null
2012-09-28 20:26:03,715 INFO  [e.services.blitz.util.BlitzConfiguration] (      main) Initializing Ice.Communicator
2012-09-28 20:26:03,858 INFO  [            ome.services.blitz.fire.Ring] (      main) Checking cluster
2012-09-28 20:26:03,871 INFO  [        ome.services.blitz.fire.Registry] (      main) Found 0 cluster node(s) : []
2012-09-28 20:26:03,871 INFO  [            ome.services.blitz.fire.Ring] (      main) Got 0 cluster uuids : []
2012-09-28 20:26:03,872 INFO  [        ome.services.util.ServiceHandler] (      main)  Executor.doWork -- ome.services.blitz.fire.Ring.getManagerList
2012-09-28 20:26:03,872 INFO  [        ome.services.util.ServiceHandler] (      main)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:04,131 INFO  [         ome.security.basic.EventHandler] (      main)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:04,137 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348878363872] time[265] tag[omero.call.success.ome.services.blitz.fire.Ring$2.doWork]
2012-09-28 20:26:04,137 INFO  [        ome.services.util.ServiceHandler] (      main)  Rslt:   (1c8232b8-f8ab-4df3-b52c-e614f17df501)
2012-09-28 20:26:04,140 INFO  [            ome.services.blitz.fire.Ring] (      main) Purging node: 1c8232b8-f8ab-4df3-b52c-e614f17df501
2012-09-28 20:26:04,367 INFO  [        ome.services.util.ServiceHandler] (      main)  Executor.doWork -- ome.services.blitz.fire.Ring.executeUpdate - set closed = now()
2012-09-28 20:26:04,367 INFO  [        ome.services.util.ServiceHandler] (      main)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:04,384 INFO  [         ome.security.basic.EventHandler] (      main)  Auth:   user=0,group=0,event=16868(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:04,390 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348878364367] time[23] tag[omero.call.success.ome.services.blitz.fire.Ring$3.doWork]
2012-09-28 20:26:04,390 INFO  [        ome.services.util.ServiceHandler] (      main)  Rslt:   0
2012-09-28 20:26:04,390 INFO  [            ome.services.blitz.fire.Ring] (      main) Removed 0 entries with value 1c8232b8-f8ab-4df3-b52c-e614f17df501
2012-09-28 20:26:04,391 INFO  [        ome.services.util.ServiceHandler] (      main)  Executor.doWork -- ome.services.blitz.fire.Ring.setManagerDown
2012-09-28 20:26:04,391 INFO  [        ome.services.util.ServiceHandler] (      main)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:04,423 INFO  [         ome.security.basic.EventHandler] (      main)  Auth:   user=0,group=0,event=16869(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:04,427 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348878364391] time[36] tag[omero.call.success.ome.services.blitz.fire.Ring$4.doWork]
2012-09-28 20:26:04,428 INFO  [        ome.services.util.ServiceHandler] (      main)  Rslt:   1
2012-09-28 20:26:04,428 INFO  [            ome.services.blitz.fire.Ring] (      main) Removed manager: 1c8232b8-f8ab-4df3-b52c-e614f17df501
2012-09-28 20:26:04,428 INFO  [            ome.services.blitz.fire.Ring] (      main) handleRingShutdown: 1c8232b8-f8ab-4df3-b52c-e614f17df501
2012-09-28 20:26:04,443 INFO  [        ome.services.util.ServiceHandler] (      main)  Executor.doWork -- ome.services.blitz.fire.Ring.addManager
2012-09-28 20:26:04,443 INFO  [        ome.services.util.ServiceHandler] (      main)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:04,462 INFO  [         ome.security.basic.EventHandler] (      main)  Auth:   user=0,group=0,event=16870(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:04,467 INFO  [       ome.security.basic.CurrentDetails] (      main) Adding log:INSERT,class ome.model.meta.Node,1501
2012-09-28 20:26:04,545 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348878364443] time[102] tag[omero.call.success.ome.services.blitz.fire.Ring$5.doWork]
2012-09-28 20:26:04,545 INFO  [        ome.services.util.ServiceHandler] (      main)  Rslt:   ome.model.meta.Node:Id_1501
2012-09-28 20:26:04,630 INFO  [        ome.services.blitz.fire.Registry] (      main) Added ClusterNode/ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5 to registry
2012-09-28 20:26:04,867 INFO  [.services.blitz.repo.AbstractRepositoryI] (      main) Initializing repository in /OMERO/
2012-09-28 20:26:04,903 INFO  [        ome.services.util.ServiceHandler] (      main)  Executor.doWork -- ome.services.scripts.ScriptRepoHelper.loadAll(true)
2012-09-28 20:26:04,903 INFO  [        ome.services.util.ServiceHandler] (      main)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:04,919 INFO  [         ome.security.basic.EventHandler] (      main)  Auth:   user=0,group=0,event=16871(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:05,231 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348878364903] time[328] tag[omero.call.success.ome.services.scripts.ScriptRepoHelper$5.doWork]
2012-09-28 20:26:05,231 INFO  [        ome.services.util.ServiceHandler] (      main)  Rslt:   (ome.model.core.OriginalFile:Id_1, ome.model.core.OriginalFile:Id_2, ome.model.core.OriginalFile:Id_3, ... 10 more)
2012-09-28 20:26:05,232 INFO  [.services.blitz.repo.AbstractRepositoryI] (      main) Initializing repository in /usr/omero/./lib/scripts
2012-09-28 20:26:05,345 INFO  [        ome.services.util.ServiceHandler] (2-thread-2)  Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.takeover
2012-09-28 20:26:05,385 INFO  [        ome.services.util.ServiceHandler] (2-thread-2)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:05,438 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Executor.doWork -- ome.services.blitz.repo.ScriptRepositoryI.takeover
2012-09-28 20:26:05,438 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:05,642 INFO  [         ome.security.basic.EventHandler] (2-thread-2)  Auth:   user=0,group=0,event=16872(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:05,647 INFO  [.services.blitz.repo.AbstractRepositoryI] (2-thread-2) Opened repository OMERO (uuid=3dbb4080-83e7-438a-a2fb-8c65af6a6a55)
2012-09-28 20:26:05,770 INFO  [                ome.services.blitz.Entry] (      main) OMERO.blitz now accepting connections.
2012-09-28 20:26:05,770 INFO  [        ome.services.blitz.fire.Registry] (2-thread-2) Updated InternalRepository-3dbb4080-83e7-438a-a2fb-8c65af6a6a55 in registry
2012-09-28 20:26:06,081 INFO  [    ome.services.blitz.util.CheckAllJobs] (  Thread-8) Waiting 120 secs. for callbacks
2012-09-28 20:26:06,105 INFO  [        ome.services.blitz.fire.Registry] (2-thread-2) Updated PublicRepository-3dbb4080-83e7-438a-a2fb-8c65af6a6a55 in registry
2012-09-28 20:26:06,125 INFO  [.services.blitz.repo.AbstractRepositoryI] (2-thread-2) Repository now active
2012-09-28 20:26:06,312 INFO  [                 org.perf4j.TimingLogger] (2-thread-2) start[1348878365385] time[927] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$GetOrCreateRepo.doWork]
2012-09-28 20:26:06,312 INFO  [        ome.services.util.ServiceHandler] (2-thread-2)  Rslt:   ome.model.core.OriginalFile:Id_14
2012-09-28 20:26:06,312 INFO  [        ome.services.util.ServiceHandler] (2-thread-2)  Executor.doWork -- ome.services.blitz.repo.LegacyRepositoryI.getDescription(14)
2012-09-28 20:26:06,312 INFO  [        ome.services.util.ServiceHandler] (2-thread-2)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:06,316 INFO  [         ome.security.basic.EventHandler] (2-thread-2)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:06,340 INFO  [         ome.security.basic.EventHandler] (2-thread-3)  Auth:   user=0,group=0,event=16873(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:06,345 INFO  [.services.blitz.repo.AbstractRepositoryI] (2-thread-3) Opened repository scripts (uuid=ScriptRepo)
2012-09-28 20:26:06,356 INFO  [        ome.services.blitz.fire.Registry] (2-thread-3) Updated InternalRepository-ScriptRepo in registry
2012-09-28 20:26:06,358 INFO  [                 org.perf4j.TimingLogger] (2-thread-2) start[1348878366313] time[45] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$1.doWork]
2012-09-28 20:26:06,358 INFO  [        ome.services.util.ServiceHandler] (2-thread-2)  Rslt:   ome.model.core.OriginalFile:Id_14
2012-09-28 20:26:06,379 INFO  [        ome.services.blitz.fire.Registry] (2-thread-3) Updated PublicRepository-ScriptRepo in registry
2012-09-28 20:26:06,379 INFO  [.services.blitz.repo.AbstractRepositoryI] (2-thread-3) Repository now active
2012-09-28 20:26:06,402 INFO  [                 org.perf4j.TimingLogger] (2-thread-3) start[1348878365438] time[964] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$GetOrCreateRepo.doWork]
2012-09-28 20:26:06,402 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Rslt:   ome.model.core.OriginalFile:Id_15
2012-09-28 20:26:06,402 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Executor.doWork -- ome.services.blitz.repo.ScriptRepositoryI.getDescription(15)
2012-09-28 20:26:06,402 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:06,404 INFO  [         ome.security.basic.EventHandler] (2-thread-3)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:06,454 INFO  [                 org.perf4j.TimingLogger] (2-thread-3) start[1348878366402] time[52] tag[omero.call.success.ome.services.blitz.repo.AbstractRepositoryI$1.doWork]
2012-09-28 20:26:06,454 INFO  [        ome.services.util.ServiceHandler] (2-thread-3)  Rslt:   ome.model.core.OriginalFile:Id_15
2012-09-28 20:26:07,080 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession
2012-09-28 20:26:07,080 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:07,107 INFO  [         ome.security.basic.EventHandler] (l.Server-5)  Auth:   user=0,group=0,event=16874(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:07,233 INFO  [       ome.security.basic.CurrentDetails] (l.Server-5) Adding log:INSERT,class ome.model.meta.Session,4649
2012-09-28 20:26:07,364 INFO  [                 org.perf4j.TimingLogger] (l.Server-5) start[1348878367080] time[284] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
2012-09-28 20:26:07,364 INFO  [        ome.services.util.ServiceHandler] (l.Server-5)  Rslt:   (ome.model.meta.Experimenter:Id_0, ome.model.meta.ExperimenterGroup:Id_0, (0, 1), ... 4 more)
2012-09-28 20:26:07,400 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-5) Created session ServiceFactoryI(session-dc8e6366-bf4c-4305-bb5e-13535f21f837/69f353f8-f293-488e-a912-87f160c0ae0c) for user root (agent=Python service)
2012-09-28 20:26:07,539 INFO  [                      omero.cmd.SessionI] (l.Server-3) Added servant to adapter: 69f353f8-f293-488e-a912-87f160c0ae0c/dc8e6366-bf4c-4305-bb5e-13535f21f837omero.api.IConfig(omero.api._IConfigTie@10e15619)
2012-09-28 20:26:07,592 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Meth:   interface ome.api.IConfig.getConfigValue
2012-09-28 20:26:07,592 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Args:   [omero.data.dir]
2012-09-28 20:26:07,644 INFO  [         ome.security.basic.EventHandler] (l.Server-3)  Auth:   user=0,group=0,event=16875(User),sess=69f353f8-f293-488e-a912-87f160c0ae0c
2012-09-28 20:26:07,668 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1348878367593] time[75] tag[omero.call.success.ome.logic.ConfigImpl.getConfigValue]
2012-09-28 20:26:07,668 INFO  [        ome.services.util.ServiceHandler] (l.Server-3)  Rslt:   /OMERO/
2012-09-28 20:26:07,669 INFO  [e.services.sessions.SessionContext$Count] (l.Server-3) -Reference count: 69f353f8-f293-488e-a912-87f160c0ae0c=0
2012-09-28 20:26:07,669 INFO  [                      omero.cmd.SessionI] (l.Server-3) doDestroy(ServiceFactoryI(session-dc8e6366-bf4c-4305-bb5e-13535f21f837/69f353f8-f293-488e-a912-87f160c0ae0c))
2012-09-28 20:26:07,670 INFO  [                      omero.cmd.SessionI] (l.Server-3) Unregistered servant:69f353f8-f293-488e-a912-87f160c0ae0c/dc8e6366-bf4c-4305-bb5e-13535f21f837omero.api.IConfig(omero.api._IConfigTie@10e15619)
2012-09-28 20:26:07,670 INFO  [                      omero.cmd.SessionI] (l.Server-3) Removed servant from adapter: dc8e6366-bf4c-4305-bb5e-13535f21f837omero.api.IConfig
2012-09-28 20:26:07,671 INFO  [ome.services.sessions.SessionManagerImpl] (l.Server-3) closeSession called and no more references: 69f353f8-f293-488e-a912-87f160c0ae0c
2012-09-28 20:26:07,671 INFO  [ome.services.sessions.state.SessionCache] (l.Server-3) Destroying session 69f353f8-f293-488e-a912-87f160c0ae0c due to : Remove session called
2012-09-28 20:26:07,671 INFO  [                 org.perf4j.TimingLogger] (l.Server-3) start[1348878367366] time[305] tag[omero.session]
2012-09-28 20:26:13,935 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.createSession
2012-09-28 20:26:13,935 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:13,947 INFO  [         ome.security.basic.EventHandler] (l.Server-7)  Auth:   user=0,group=0,event=16876(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:13,959 INFO  [       ome.security.basic.CurrentDetails] (l.Server-7) Adding log:INSERT,class ome.model.meta.Session,4650
2012-09-28 20:26:13,971 INFO  [                 org.perf4j.TimingLogger] (l.Server-7) start[1348878373935] time[36] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$2.doWork]
2012-09-28 20:26:13,972 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)
2012-09-28 20:26:13,972 INFO  [ ome.services.blitz.fire.SessionManagerI] (l.Server-7) Created session ServiceFactoryI(session-cce69ebf-6eb8-465d-b1ac-ea8bd4a70ae5/eeb642f1-f0ff-4e27-88a7-cce3a4e813df) for user root (agent=Python service)
2012-09-28 20:26:13,980 INFO  [    ome.services.blitz.util.CheckAllJobs] (l.Server-5) Received 0 job(s)
2012-09-28 20:26:13,991 INFO  [                      omero.cmd.SessionI] (l.Server-6) Added servant to adapter: eeb642f1-f0ff-4e27-88a7-cce3a4e813df/cce69ebf-6eb8-465d-b1ac-ea8bd4a70ae5omero.grid.SharedResources(omero.grid._SharedResourcesTie@f9a6fc1)
2012-09-28 20:26:21,832 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(dennis)
2012-09-28 20:26:21,833 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:21,834 INFO  [         ome.security.basic.EventHandler] (l.Server-8)  Auth:   user=0,group=0,event=null(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:21,836 INFO  [                 org.perf4j.TimingLogger] (l.Server-8) start[1348878381833] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2012-09-28 20:26:21,836 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Rslt:   false
2012-09-28 20:26:21,837 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Executor.doWork -- java.lang.String.dennis
2012-09-28 20:26:21,837 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:21,838 INFO  [         ome.security.basic.EventHandler] (l.Server-8)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:21,841 INFO  [                 org.perf4j.TimingLogger] (l.Server-8) start[1348878381837] time[4] tag[omero.call.success.ome.services.blitz.fire.PermissionsVerifierI$1.doWork]
2012-09-28 20:26:21,841 INFO  [        ome.services.util.ServiceHandler] (l.Server-8)  Rslt:   null
2012-09-28 20:26:36,460 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(root)
2012-09-28 20:26:36,460 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:36,462 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:   user=0,group=0,event=null(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:36,467 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1348878396460] time[7] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2012-09-28 20:26:36,467 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:   false
2012-09-28 20:26:36,473 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Executor.doWork -- java.lang.String.root
2012-09-28 20:26:36,473 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Args:   [null, InternalSF@19980907]
2012-09-28 20:26:36,474 INFO  [         ome.security.basic.EventHandler] (l.Server-2)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:26:36,479 INFO  [                 org.perf4j.TimingLogger] (l.Server-2) start[1348878396473] time[6] tag[omero.call.success.ome.services.blitz.fire.PermissionsVerifierI$1.doWork]
2012-09-28 20:26:36,479 INFO  [        ome.services.util.ServiceHandler] (l.Server-2)  Rslt:   null
2012-09-28 20:27:00,014 INFO  [ ome.services.blitz.fire.SessionManagerI] (2-thread-4) Performing requestHeartbeats
2012-09-28 20:27:00,972 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(root)
2012-09-28 20:27:00,972 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:   [null, InternalSF@19980907]
2012-09-28 20:27:00,974 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:   user=0,group=0,event=null(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:27:00,975 WARN  [  ome.security.auth.LoginAttemptListener] (l.Server-9) 2 failed logins for root. Throttling for 3000
2012-09-28 20:27:03,977 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1348878420972] time[3005] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2012-09-28 20:27:03,977 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:   false
2012-09-28 20:27:03,978 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Executor.doWork -- java.lang.String.root
2012-09-28 20:27:03,978 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:   [null, InternalSF@19980907]
2012-09-28 20:27:03,979 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:27:03,982 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1348878423978] time[4] tag[omero.call.success.ome.services.blitz.fire.PermissionsVerifierI$1.doWork]
2012-09-28 20:27:03,982 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:   null
2012-09-28 20:27:13,887 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(omero)
2012-09-28 20:27:13,887 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Args:   [null, InternalSF@19980907]
2012-09-28 20:27:13,889 INFO  [         ome.security.basic.EventHandler] (l.Server-4)  Auth:   user=0,group=0,event=null(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:27:13,890 INFO  [                 org.perf4j.TimingLogger] (l.Server-4) start[1348878433887] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2012-09-28 20:27:13,890 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Rslt:   false
2012-09-28 20:27:13,890 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Executor.doWork -- java.lang.String.omero
2012-09-28 20:27:13,890 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Args:   [null, InternalSF@19980907]
2012-09-28 20:27:13,892 INFO  [         ome.security.basic.EventHandler] (l.Server-4)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:27:13,894 INFO  [                 org.perf4j.TimingLogger] (l.Server-4) start[1348878433890] time[4] tag[omero.call.success.ome.services.blitz.fire.PermissionsVerifierI$1.doWork]
2012-09-28 20:27:13,894 INFO  [        ome.services.util.ServiceHandler] (l.Server-4)  Rslt:   null
2012-09-28 20:27:17,139 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(omero)
2012-09-28 20:27:17,139 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:   [null, InternalSF@19980907]
2012-09-28 20:27:17,141 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:   user=0,group=0,event=null(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:27:17,142 WARN  [  ome.security.auth.LoginAttemptListener] (l.Server-1) 2 failed logins for omero. Throttling for 3000
2012-09-28 20:27:20,144 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1348878437139] time[3005] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2012-09-28 20:27:20,144 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:   false
2012-09-28 20:27:20,145 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Executor.doWork -- java.lang.String.omero
2012-09-28 20:27:20,145 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Args:   [null, InternalSF@19980907]
2012-09-28 20:27:20,146 INFO  [         ome.security.basic.EventHandler] (l.Server-1)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:27:20,149 INFO  [                 org.perf4j.TimingLogger] (l.Server-1) start[1348878440145] time[4] tag[omero.call.success.ome.services.blitz.fire.PermissionsVerifierI$1.doWork]
2012-09-28 20:27:20,149 INFO  [        ome.services.util.ServiceHandler] (l.Server-1)  Rslt:   null
2012-09-28 20:27:49,368 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(6fdfda59-1c75-48bb-b7ba-41d0bb667623)
2012-09-28 20:27:49,368 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:   [null, InternalSF@19980907]
2012-09-28 20:27:49,370 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:   user=0,group=0,event=null(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:27:49,371 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1348878469368] time[3] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2012-09-28 20:27:49,371 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:   false
2012-09-28 20:27:49,371 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Executor.doWork -- java.lang.String.6fdfda59-1c75-48bb-b7ba-41d0bb667623
2012-09-28 20:27:49,371 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Args:   [null, InternalSF@19980907]
2012-09-28 20:27:49,375 INFO  [         ome.security.basic.EventHandler] (l.Server-9)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:27:49,379 INFO  [                 org.perf4j.TimingLogger] (l.Server-9) start[1348878469371] time[8] tag[omero.call.success.ome.services.blitz.fire.PermissionsVerifierI$1.doWork]
2012-09-28 20:27:49,379 INFO  [        ome.services.util.ServiceHandler] (l.Server-9)  Rslt:   null
2012-09-28 20:28:19,185 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Executor.doWork -- ome.services.sessions.SessionManagerImpl.executeCheckPasswordRO(root)
2012-09-28 20:28:19,185 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Args:   [null, InternalSF@19980907]
2012-09-28 20:28:19,186 INFO  [         ome.security.basic.EventHandler] (l.Server-7)  Auth:   user=0,group=0,event=null(Sessions),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:28:19,187 WARN  [  ome.security.auth.LoginAttemptListener] (l.Server-7) 3 failed logins for root. Throttling for 3000
2012-09-28 20:28:22,190 INFO  [                 org.perf4j.TimingLogger] (l.Server-7) start[1348878499185] time[3005] tag[omero.call.success.ome.services.sessions.SessionManagerImpl$8.doWork]
2012-09-28 20:28:22,190 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Rslt:   false
2012-09-28 20:28:22,190 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Executor.doWork -- java.lang.String.root
2012-09-28 20:28:22,190 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Args:   [null, InternalSF@19980907]
2012-09-28 20:28:22,191 INFO  [         ome.security.basic.EventHandler] (l.Server-7)  Auth:   user=0,group=0,event=null(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:28:22,194 INFO  [                 org.perf4j.TimingLogger] (l.Server-7) start[1348878502190] time[4] tag[omero.call.success.ome.services.blitz.fire.PermissionsVerifierI$1.doWork]
2012-09-28 20:28:22,195 INFO  [        ome.services.util.ServiceHandler] (l.Server-7)  Rslt:   null
2012-09-28 20:29:02,003 WARN  [                      omero.cmd.SessionI] (l.Server-4) Adapter already deactivated. Cannot remove: Ice.Identity@eed05877
2012-09-28 20:29:02,003 INFO  [e.services.sessions.SessionContext$Count] (l.Server-4) -Reference count: eeb642f1-f0ff-4e27-88a7-cce3a4e813df=0
2012-09-28 20:29:02,015 INFO  [                      omero.cmd.SessionI] (l.Server-4) doDestroy(ServiceFactoryI(session-cce69ebf-6eb8-465d-b1ac-ea8bd4a70ae5/eeb642f1-f0ff-4e27-88a7-cce3a4e813df))
2012-09-28 20:29:02,016 WARN  [ome.services.blitz.impl.SharedResourcesI] (l.Server-4) ObjectAdapter deactivated!
2012-09-28 20:29:02,038 ERROR [                      omero.cmd.SessionI] (l.Server-4) Error destroying servant: cce69ebf-6eb8-465d-b1ac-ea8bd4a70ae5omero.grid.SharedResources=ome.services.blitz.impl.SharedResourcesI@3eca5e
java.lang.RuntimeException: omero.ShutdownInProgress
    serverStackTrace = "Ice.ObjectAdapterDeactivatedException
                            name = "BlitzAdapter"
                           at Ice.ObjectAdapterI.checkForDeactivation(ObjectAdapterI.java:1121)
                           at Ice.ObjectAdapterI.findFacet(ObjectAdapterI.java:505)
                           at Ice.ObjectAdapterI.find(ObjectAdapterI.java:499)
                           at omero.cmd.SessionI.unregisterServant(SessionI.java:616)
                           at ome.services.blitz.fire.SessionManagerI.onApplicationEvent(SessionManagerI.java:294)
                           at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:97)
                           at ome.services.messages.GlobalMulticaster.multicastEvent(GlobalMulticaster.java:55)
                           at ome.system.OmeroContext.publishEvent(OmeroContext.java:327)
                           at ome.system.OmeroContext.publishMessage(OmeroContext.java:341)
                           at ome.services.blitz.impl.AbstractAmdServant.close_async(AbstractAmdServant.java:254)
                           at ome.services.blitz.impl.AbstractAmdServant.close(AbstractAmdServant.java:212)
                           at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
                           at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
                           at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
                           at java.lang.reflect.Method.invoke(Method.java:601)
                           at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
                           at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
                           at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
                           at omero.cmd.CallContext.invoke(CallContext.java:59)
                           at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
                           at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
                           at $Proxy76.close(Unknown Source)
                           at omero.cmd.SessionI.cleanServants(SessionI.java:443)
                           at omero.cmd.SessionI.cleanServants(SessionI.java:379)
                           at omero.cmd.SessionI.doDestroy(SessionI.java:369)
                           at omero.cmd.SessionI.destroy(SessionI.java:297)
                           at omero.api._ServiceFactoryTie.destroy(_ServiceFactoryTie.java:72)
                           at Glacier2._SessionDisp.___destroy(_SessionDisp.java:104)
                           at omero.api._ServiceFactoryDisp.__dispatch(_ServiceFactoryDisp.java:1420)
                           at IceInternal.Incoming.invoke(Incoming.java:159)
                           at Ice.ConnectionI.invokeAll(ConnectionI.java:2357)
                           at Ice.ConnectionI.dispatch(ConnectionI.java:1208)
                           at Ice.ConnectionI.message(ConnectionI.java:1163)
                           at IceInternal.ThreadPool.run(ThreadPool.java:302)
                           at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
                           at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:643)
                           at java.lang.Thread.run(Thread.java:722)
                        "
    serverExceptionClass = "Ice.ObjectAdapterDeactivatedException"
    message = (null)
   at ome.services.blitz.impl.AbstractAmdServant$1.ice_exception(AbstractAmdServant.java:205)
   at ome.services.blitz.impl.AbstractAmdServant.close_async(AbstractAmdServant.java:269)
   at ome.services.blitz.impl.AbstractAmdServant.close(AbstractAmdServant.java:212)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at omero.cmd.CallContext.invoke(CallContext.java:59)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy76.close(Unknown Source)
   at omero.cmd.SessionI.cleanServants(SessionI.java:443)
   at omero.cmd.SessionI.cleanServants(SessionI.java:379)
   at omero.cmd.SessionI.doDestroy(SessionI.java:369)
   at omero.cmd.SessionI.destroy(SessionI.java:297)
   at omero.api._ServiceFactoryTie.destroy(_ServiceFactoryTie.java:72)
   at Glacier2._SessionDisp.___destroy(_SessionDisp.java:104)
   at omero.api._ServiceFactoryDisp.__dispatch(_ServiceFactoryDisp.java:1420)
   at IceInternal.Incoming.invoke(Incoming.java:159)
   at Ice.ConnectionI.invokeAll(ConnectionI.java:2357)
   at Ice.ConnectionI.dispatch(ConnectionI.java:1208)
   at Ice.ConnectionI.message(ConnectionI.java:1163)
   at IceInternal.ThreadPool.run(ThreadPool.java:302)
   at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
   at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:643)
   at java.lang.Thread.run(Thread.java:722)
Caused by: omero.ShutdownInProgress
    serverStackTrace = "Ice.ObjectAdapterDeactivatedException
                            name = "BlitzAdapter"
                           at Ice.ObjectAdapterI.checkForDeactivation(ObjectAdapterI.java:1121)
                           at Ice.ObjectAdapterI.findFacet(ObjectAdapterI.java:505)
                           at Ice.ObjectAdapterI.find(ObjectAdapterI.java:499)
                           at omero.cmd.SessionI.unregisterServant(SessionI.java:616)
                           at ome.services.blitz.fire.SessionManagerI.onApplicationEvent(SessionManagerI.java:294)
                           at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:97)
                           at ome.services.messages.GlobalMulticaster.multicastEvent(GlobalMulticaster.java:55)
                           at ome.system.OmeroContext.publishEvent(OmeroContext.java:327)
                           at ome.system.OmeroContext.publishMessage(OmeroContext.java:341)
                           at ome.services.blitz.impl.AbstractAmdServant.close_async(AbstractAmdServant.java:254)
                           at ome.services.blitz.impl.AbstractAmdServant.close(AbstractAmdServant.java:212)
                           at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
                           at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
                           at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
                           at java.lang.reflect.Method.invoke(Method.java:601)
                           at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
                           at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
                           at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
                           at omero.cmd.CallContext.invoke(CallContext.java:59)
                           at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
                           at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
                           at $Proxy76.close(Unknown Source)
                           at omero.cmd.SessionI.cleanServants(SessionI.java:443)
                           at omero.cmd.SessionI.cleanServants(SessionI.java:379)
                           at omero.cmd.SessionI.doDestroy(SessionI.java:369)
                           at omero.cmd.SessionI.destroy(SessionI.java:297)
                           at omero.api._ServiceFactoryTie.destroy(_ServiceFactoryTie.java:72)
                           at Glacier2._SessionDisp.___destroy(_SessionDisp.java:104)
                           at omero.api._ServiceFactoryDisp.__dispatch(_ServiceFactoryDisp.java:1420)
                           at IceInternal.Incoming.invoke(Incoming.java:159)
                           at Ice.ConnectionI.invokeAll(ConnectionI.java:2357)
                           at Ice.ConnectionI.dispatch(ConnectionI.java:1208)
                           at Ice.ConnectionI.message(ConnectionI.java:1163)
                           at IceInternal.ThreadPool.run(ThreadPool.java:302)
                           at IceInternal.ThreadPool.access$300(ThreadPool.java:12)
                           at IceInternal.ThreadPool$EventHandlerThread.run(ThreadPool.java:643)
                           at java.lang.Thread.run(Thread.java:722)
                        "
    serverExceptionClass = "Ice.ObjectAdapterDeactivatedException"
    message = (null)
   at ome.services.blitz.impl.AbstractAmdServant.close_async(AbstractAmdServant.java:257)
   ... 27 more
2012-09-28 20:29:02,076 INFO  [                ome.services.blitz.Entry] (      main) Calling close on context OMERO.blitz
2012-09-28 20:29:02,078 INFO  [.services.blitz.repo.AbstractRepositoryI] (      main) Releasing /usr/omero/./lib/scripts
2012-09-28 20:29:02,079 INFO  [.services.blitz.repo.AbstractRepositoryI] (      main) Releasing /OMERO/
2012-09-28 20:29:02,091 INFO  [        ome.services.blitz.fire.Registry] (      main) Removed ClusterNode/ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5 from registry
2012-09-28 20:29:02,092 INFO  [        ome.services.util.ServiceHandler] (      main)  Executor.doWork -- ome.services.blitz.fire.Ring.executeUpdate - set closed = now()
2012-09-28 20:29:02,092 INFO  [        ome.services.util.ServiceHandler] (      main)  Args:   [null, InternalSF@19980907]
2012-09-28 20:29:02,106 INFO  [         ome.security.basic.EventHandler] (      main)  Auth:   user=0,group=0,event=16877(Internal),sess=ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:29:02,109 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348878542092] time[17] tag[omero.call.success.ome.services.blitz.fire.Ring$3.doWork]
2012-09-28 20:29:02,109 INFO  [        ome.services.util.ServiceHandler] (      main)  Rslt:   1
2012-09-28 20:29:02,109 INFO  [            ome.services.blitz.fire.Ring] (      main) Removed 1 entries for ec1fe0c7-edbc-4101-95a6-ee0aaf9816d5
2012-09-28 20:29:02,109 INFO  [            ome.services.blitz.fire.Ring] (      main) Disconnected from OMERO.cluster
2012-09-28 20:29:02,110 INFO  [        ome.services.blitz.fire.Registry] (      main) Found 0 cluster node(s) : []
2012-09-28 20:29:02,110 INFO  [e.services.blitz.util.BlitzConfiguration] (      main) Shutting down Ice.Communicator
2012-09-28 20:29:02,118 INFO  [    ome.services.util.ServerVersionCheck] (      main) -------------------------------------------------
2012-09-28 20:29:02,118 INFO  [    ome.services.util.ServerVersionCheck] (      main) Stopping OMERO...
2012-09-28 20:29:02,118 INFO  [    ome.services.util.ServerVersionCheck] (      main) -------------------------------------------------
2012-09-28 20:29:02,118 INFO  [  ome.services.pixeldata.PixelDataThread] (      main) Shutting down PixelDataThread
2012-09-28 20:29:02,119 INFO  [    ome.services.fulltext.FullTextThread] (      main) Shutting down Full-Text Indexer
2012-09-28 20:29:02,119 INFO  [.services.scheduler.SchedulerFactoryBean] (      main) Shutting down Quartz Scheduler
2012-09-28 20:29:02,137 INFO  [ng.ShutdownSafeEhcacheManagerFactoryBean] (      main) Shutting down EHCache CacheManager
2012-09-28 20:29:02,137 INFO  [                ome.services.blitz.Entry] (      main) Finished shutdown.
2012-09-28 20:29:49,067 INFO  [                ome.services.blitz.Entry] (      main) Creating OMERO.blitz. Please wait...
2012-09-28 20:29:52,924 INFO  [ng.ShutdownSafeEhcacheManagerFactoryBean] (      main) Initializing EHCache CacheManager
2012-09-28 20:29:56,182 INFO  [  ome.services.fulltext.FullTextAnalyzer] (      main) Initialized FullTextAnalyzer
2012-09-28 20:29:59,392 INFO  [          ome.services.util.DBPatchCheck] (      main) Verified database patch: OMERO4.4__0
2012-09-28 20:29:59,558 INFO  [ng.ShutdownSafeEhcacheManagerFactoryBean] (      main) Shutting down EHCache CacheManager
2012-09-28 20:29:59,559 ERROR [                ome.services.blitz.Entry] (      main) Error on startup.
org.springframework.beans.factory.access.BootstrapException: Unable to return specified BeanFactory instance: factory key [OMERO.blitz], from group with resource name [classpath*:beanRefContext.xml]; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'OMERO.blitz' defined in URL [jar:file:/usr/omero/lib/server/blitz.jar!/beanRefContext.xml]: Cannot resolve reference to bean 'ome.server' while setting constructor argument; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'ome.server' defined in URL [jar:file:/usr/omero/lib/server/server.jar!/beanRefContext.xml]: Instantiation of bean failed; nested exception is org.springframework.beans.BeanInstantiationException: Could not instantiate bean class [ome.system.OmeroContext]: Constructor threw exception; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'sessionManager' defined in class path resource [ome/services/sec-primitives.xml]: Invocation of init method failed; nested exception is java.lang.RuntimeException:           =====================================================
Data access exception: Did you create your database?
=====================================================

   at org.springframework.beans.factory.access.SingletonBeanFactoryLocator.useBeanFactory(SingletonBeanFactoryLocator.java:409)
   at ome.system.OmeroContext.getInstance(OmeroContext.java:203)
   at ome.services.blitz.Entry.start(Entry.java:233)
   at ome.services.blitz.Entry.main(Entry.java:151)
Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'OMERO.blitz' defined in URL [jar:file:/usr/omero/lib/server/blitz.jar!/beanRefContext.xml]: Cannot resolve reference to bean 'ome.server' while setting constructor argument; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'ome.server' defined in URL [jar:file:/usr/omero/lib/server/server.jar!/beanRefContext.xml]: Instantiation of bean failed; nested exception is org.springframework.beans.BeanInstantiationException: Could not instantiate bean class [ome.system.OmeroContext]: Constructor threw exception; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'sessionManager' defined in class path resource [ome/services/sec-primitives.xml]: Invocation of init method failed; nested exception is java.lang.RuntimeException:           =====================================================
Data access exception: Did you create your database?
=====================================================

   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:328)
   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:106)
   at org.springframework.beans.factory.support.ConstructorResolver.resolveConstructorArguments(ConstructorResolver.java:586)
   at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:140)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:984)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:888)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:479)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:193)
   at org.springframework.context.support.AbstractApplicationContext.getBean(AbstractApplicationContext.java:1048)
   at org.springframework.beans.factory.access.SingletonBeanFactoryLocator.useBeanFactory(SingletonBeanFactoryLocator.java:397)
   ... 3 more
Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'ome.server' defined in URL [jar:file:/usr/omero/lib/server/server.jar!/beanRefContext.xml]: Instantiation of bean failed; nested exception is org.springframework.beans.BeanInstantiationException: Could not instantiate bean class [ome.system.OmeroContext]: Constructor threw exception; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'sessionManager' defined in class path resource [ome/services/sec-primitives.xml]: Invocation of init method failed; nested exception is java.lang.RuntimeException:           =====================================================
Data access exception: Did you create your database?
=====================================================

   at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:281)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:984)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:888)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:479)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:189)
   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:322)
   ... 16 more
Caused by: org.springframework.beans.BeanInstantiationException: Could not instantiate bean class [ome.system.OmeroContext]: Constructor threw exception; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'sessionManager' defined in class path resource [ome/services/sec-primitives.xml]: Invocation of init method failed; nested exception is java.lang.RuntimeException:           =====================================================
Data access exception: Did you create your database?
=====================================================

   at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:141)
   at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:107)
   at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:273)
   ... 25 more
Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'sessionManager' defined in class path resource [ome/services/sec-primitives.xml]: Invocation of init method failed; nested exception is java.lang.RuntimeException:           =====================================================
Data access exception: Did you create your database?
=====================================================

   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1403)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:513)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:189)
   at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:562)
   at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:871)
   at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:423)
   at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
   at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:105)
   at ome.system.OmeroContext.<init>(OmeroContext.java:98)
   at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
   at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
   at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
   at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
   at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:126)
   ... 27 more
Caused by: java.lang.RuntimeException:           =====================================================
Data access exception: Did you create your database?
=====================================================
pkMyt1
 
Posts: 12
Joined: Sat Sep 29, 2012 2:07 am

Re: DB Connection error with Postgresql 9.1

Postby cxallan » Wed Oct 03, 2012 8:03 am

The exception we'd need to see would be right after what you've pasted. Can you attach the entire log file here or put it up somewhere where we can see it?
cxallan
Site Admin
 
Posts: 509
Joined: Fri May 01, 2009 8:07 am

Re: DB Connection error with Postgresql 9.1

Postby pkMyt1 » Wed Oct 03, 2012 1:11 pm

It's too large to paste the whole thing and unfortunately the university frowns on us granting temporary access to our servers but here are the next 500 lines or so. It begins at the next line after what I posted above and ends where built and connected to a new db with the backup. This should contain what you seek. Thank you again for looking into this. I really suspect it is something I did but I haven't a clue as to what.
Code: Select all
   at ome.services.sessions.SessionManagerImpl.init(SessionManagerImpl.java:189)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1527)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1468)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1400)
   ... 44 more
Caused by: org.springframework.dao.DuplicateKeyException: PreparedStatementCallback; SQL [insert into session (id,permissions,timetoidle,timetolive,started,closed,defaulteventtype,uuid,owner,node) values (?,-35,?,?,?,null,?,?,?,?)]; ERROR: duplicate key value violates unique constraint "session_pkey"
  Detail: Key (id)=(4646) already exists.; nested exception is org.postgresql.util.PSQLException: ERROR: duplicate key value violates unique constraint "session_pkey"
  Detail: Key (id)=(4646) already exists.
   at org.springframework.jdbc.support.SQLErrorCodeSQLExceptionTranslator.doTranslate(SQLErrorCodeSQLExceptionTranslator.java:241)
   at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:72)
   at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:602)
   at org.springframework.jdbc.core.JdbcTemplate.update(JdbcTemplate.java:811)
   at org.springframework.jdbc.core.JdbcTemplate.update(JdbcTemplate.java:833)
   at org.springframework.jdbc.core.namedparam.NamedParameterJdbcTemplate.update(NamedParameterJdbcTemplate.java:233)
   at org.springframework.jdbc.core.namedparam.NamedParameterJdbcTemplate.update(NamedParameterJdbcTemplate.java:237)
   at org.springframework.jdbc.core.simple.SimpleJdbcTemplate.update(SimpleJdbcTemplate.java:239)
   at ome.util.actions.PostgresSqlAction.insertSession(PostgresSqlAction.java:186)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at ome.util.SqlAction$LoggingSqlAction.invoke(SqlAction.java:73)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy0.insertSession(Unknown Source)
   at ome.services.sessions.SessionManagerImpl$11.doWork(SessionManagerImpl.java:1056)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:108)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy54.doWork(Unknown Source)
   at ome.services.util.Executor$Impl.executeSql(Executor.java:489)
   at ome.services.sessions.SessionManagerImpl.executeInternalSession(SessionManagerImpl.java:1019)
   at ome.services.sessions.SessionManagerImpl.init(SessionManagerImpl.java:185)
   ... 51 more
Caused by: org.postgresql.util.PSQLException: ERROR: duplicate key value violates unique constraint "session_pkey"
  Detail: Key (id)=(4646) already exists.
   at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2103)
   at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1836)
   at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:257)
   at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:512)
   at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:388)
   at org.postgresql.jdbc2.AbstractJdbc2Statement.executeUpdate(AbstractJdbc2Statement.java:334)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at bitronix.tm.resource.jdbc.BaseProxyHandlerClass.invoke(BaseProxyHandlerClass.java:63)
   at $Proxy57.executeUpdate(Unknown Source)
   at org.springframework.jdbc.core.JdbcTemplate$2.doInPreparedStatement(JdbcTemplate.java:817)
   at org.springframework.jdbc.core.JdbcTemplate$2.doInPreparedStatement(JdbcTemplate.java:1)
   at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:586)
   ... 83 more
2012-09-28 21:53:27,686 INFO  [                ome.services.blitz.Entry] (      main) Creating OMERO.blitz. Please wait...
2012-09-28 21:53:31,069 INFO  [ng.ShutdownSafeEhcacheManagerFactoryBean] (      main) Initializing EHCache CacheManager
2012-09-28 21:53:34,335 INFO  [  ome.services.fulltext.FullTextAnalyzer] (      main) Initialized FullTextAnalyzer
2012-09-28 21:53:37,745 FATAL [          ome.services.util.DBPatchCheck] (      main)
***************************************************************************************
Error connecting to database table dbpatch. You may need to bootstrap.
See http://www.openmicroscopy.org/site/support/omero4/server/upgrade
***************************************************************************************

org.springframework.jdbc.BadSqlGrammarException: StatementCallback; bad SQL grammar [select currentversion, currentpatch from dbpatch order by id desc limit 1]; nested exception is org.postgresql.util.PSQLException: ERROR: permission denied for relation dbpatch
   at org.springframework.jdbc.support.SQLStateSQLExceptionTranslator.doTranslate(SQLStateSQLExceptionTranslator.java:98)
   at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:72)
   at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:80)
   at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:80)
   at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:406)
   at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:455)
   at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:463)
   at org.springframework.jdbc.core.simple.SimpleJdbcTemplate.query(SimpleJdbcTemplate.java:199)
   at ome.util.actions.PostgresSqlAction.dbVersion(PostgresSqlAction.java:212)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
   at ome.util.SqlAction$LoggingSqlAction.invoke(SqlAction.java:73)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
   at $Proxy0.dbVersion(Unknown Source)
   at ome.services.util.DBPatchCheck.start(DBPatchCheck.java:59)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1527)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1468)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1400)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:513)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:189)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:280)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:189)
   at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:562)
   at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:871)
   at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:423)
   at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
   at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:105)
   at ome.system.OmeroContext.<init>(OmeroContext.java:98)
   at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
   at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
   at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
   at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
   at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:126)
   at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:107)
   at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:273)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:984)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:888)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:479)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:189)
   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:322)
   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:106)
   at org.springframework.beans.factory.support.ConstructorResolver.resolveConstructorArguments(ConstructorResolver.java:586)
   at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:140)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:984)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:888)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:479)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:193)
   at org.springframework.context.support.AbstractApplicationContext.getBean(AbstractApplicationContext.java:1048)
   at org.springframework.beans.factory.access.SingletonBeanFactoryLocator.useBeanFactory(SingletonBeanFactoryLocator.java:397)
   at ome.system.OmeroContext.getInstance(OmeroContext.java:203)
   at ome.services.blitz.Entry.start(Entry.java:233)
   at ome.services.blitz.Entry.main(Entry.java:151)
Caused by: org.postgresql.util.PSQLException: ERROR: permission denied for relation dbpatch
   at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2103)
   at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1836)
   at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:257)
   at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:512)
   at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:374)
   at org.postgresql.jdbc2.AbstractJdbc2Statement.executeQuery(AbstractJdbc2Statement.java:254)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at bitronix.tm.resource.jdbc.BaseProxyHandlerClass.invoke(BaseProxyHandlerClass.java:63)
   at $Proxy52.executeQuery(Unknown Source)
   at org.springframework.jdbc.core.JdbcTemplate$1QueryStatementCallback.doInStatement(JdbcTemplate.java:440)
   at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:395)
   ... 69 more
2012-09-28 21:53:37,755 INFO  [ng.ShutdownSafeEhcacheManagerFactoryBean] (      main) Shutting down EHCache CacheManager
2012-09-28 21:53:37,755 ERROR [                ome.services.blitz.Entry] (      main) Error on startup.
org.springframework.beans.factory.access.BootstrapException: Unable to return specified BeanFactory instance: factory key [OMERO.blitz], from group with resource name [classpath*:beanRefContext.xml]; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'OMERO.blitz' defined in URL [jar:file:/usr/omero/lib/server/blitz.jar!/beanRefContext.xml]: Cannot resolve reference to bean 'ome.server' while setting constructor argument; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'ome.server' defined in URL [jar:file:/usr/omero/lib/server/server.jar!/beanRefContext.xml]: Instantiation of bean failed; nested exception is org.springframework.beans.BeanInstantiationException: Could not instantiate bean class [ome.system.OmeroContext]: Constructor threw exception; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'dbPatchCheck' defined in class path resource [ome/services/startup.xml]: Invocation of init method failed; nested exception is ome.conditions.InternalException:
***************************************************************************************
Error connecting to database table dbpatch. You may need to bootstrap.
See http://www.openmicroscopy.org/site/support/omero4/server/upgrade
***************************************************************************************

   at org.springframework.beans.factory.access.SingletonBeanFactoryLocator.useBeanFactory(SingletonBeanFactoryLocator.java:409)
   at ome.system.OmeroContext.getInstance(OmeroContext.java:203)
   at ome.services.blitz.Entry.start(Entry.java:233)
   at ome.services.blitz.Entry.main(Entry.java:151)
Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'OMERO.blitz' defined in URL [jar:file:/usr/omero/lib/server/blitz.jar!/beanRefContext.xml]: Cannot resolve reference to bean 'ome.server' while setting constructor argument; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'ome.server' defined in URL [jar:file:/usr/omero/lib/server/server.jar!/beanRefContext.xml]: Instantiation of bean failed; nested exception is org.springframework.beans.BeanInstantiationException: Could not instantiate bean class [ome.system.OmeroContext]: Constructor threw exception; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'dbPatchCheck' defined in class path resource [ome/services/startup.xml]: Invocation of init method failed; nested exception is ome.conditions.InternalException:
***************************************************************************************
Error connecting to database table dbpatch. You may need to bootstrap.
See http://www.openmicroscopy.org/site/support/omero4/server/upgrade
***************************************************************************************

   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:328)
   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:106)
   at org.springframework.beans.factory.support.ConstructorResolver.resolveConstructorArguments(ConstructorResolver.java:586)
   at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:140)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:984)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:888)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:479)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:193)
   at org.springframework.context.support.AbstractApplicationContext.getBean(AbstractApplicationContext.java:1048)
   at org.springframework.beans.factory.access.SingletonBeanFactoryLocator.useBeanFactory(SingletonBeanFactoryLocator.java:397)
   ... 3 more
Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'ome.server' defined in URL [jar:file:/usr/omero/lib/server/server.jar!/beanRefContext.xml]: Instantiation of bean failed; nested exception is org.springframework.beans.BeanInstantiationException: Could not instantiate bean class [ome.system.OmeroContext]: Constructor threw exception; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'dbPatchCheck' defined in class path resource [ome/services/startup.xml]: Invocation of init method failed; nested exception is ome.conditions.InternalException:
***************************************************************************************
Error connecting to database table dbpatch. You may need to bootstrap.
See http://www.openmicroscopy.org/site/support/omero4/server/upgrade
***************************************************************************************

   at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:281)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.autowireConstructor(AbstractAutowireCapableBeanFactory.java:984)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:888)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:479)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:189)
   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:322)
   ... 16 more
Caused by: org.springframework.beans.BeanInstantiationException: Could not instantiate bean class [ome.system.OmeroContext]: Constructor threw exception; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'dbPatchCheck' defined in class path resource [ome/services/startup.xml]: Invocation of init method failed; nested exception is ome.conditions.InternalException:
***************************************************************************************
Error connecting to database table dbpatch. You may need to bootstrap.
See http://www.openmicroscopy.org/site/support/omero4/server/upgrade
***************************************************************************************

   at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:141)
   at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:107)
   at org.springframework.beans.factory.support.ConstructorResolver.autowireConstructor(ConstructorResolver.java:273)
   ... 25 more
Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'dbPatchCheck' defined in class path resource [ome/services/startup.xml]: Invocation of init method failed; nested exception is ome.conditions.InternalException:
***************************************************************************************
Error connecting to database table dbpatch. You may need to bootstrap.
See http://www.openmicroscopy.org/site/support/omero4/server/upgrade
***************************************************************************************

   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1403)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:513)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:450)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:290)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:287)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:189)
   at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:280)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:189)
   at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:562)
   at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:871)
   at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:423)
   at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
   at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:105)
   at ome.system.OmeroContext.<init>(OmeroContext.java:98)
   at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
   at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
   at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
   at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
   at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:126)
   ... 27 more
Caused by: ome.conditions.InternalException:
***************************************************************************************
Error connecting to database table dbpatch. You may need to bootstrap.
See http://www.openmicroscopy.org/site/support/omero4/server/upgrade
***************************************************************************************

   at ome.services.util.DBPatchCheck.start(DBPatchCheck.java:64)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:601)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1527)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1468)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1400)
   ... 46 more
2012-09-29 10:14:56,515 INFO  [                ome.services.blitz.Entry] (      main) Creating OMERO.blitz. Please wait...
2012-09-29 10:14:59,827 INFO  [ng.ShutdownSafeEhcacheManagerFactoryBean] (      main) Initializing EHCache CacheManager
2012-09-29 10:15:03,010 INFO  [  ome.services.fulltext.FullTextAnalyzer] (      main) Initialized FullTextAnalyzer
2012-09-29 10:15:06,657 INFO  [          ome.services.util.DBPatchCheck] (      main) Verified database patch: OMERO4.4__0
2012-09-29 10:15:07,032 INFO  [        ome.services.db.DatabaseIdentity] (      main) Using LSID format: urn:lsid:export.openmicroscopy.org:%s:c61082b0-d467-4ce9-9c31-5e1cb5ff9de4_%s%s
2012-09-29 10:15:08,424 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928107073] time[1351] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:08,775 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928108424] time[351] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:09,239 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928108775] time[464] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:09,397 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928109239] time[158] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:09,498 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928109397] time[101] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:10,377 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928109498] time[879] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:11,797 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928110377] time[1420] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:11,950 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928111797] time[153] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,010 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928111951] time[58] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,115 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112010] time[105] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,177 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112115] time[62] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,233 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112177] time[56] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,288 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112234] time[54] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,515 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112288] time[227] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,573 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112515] time[58] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,630 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112573] time[57] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,709 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112630] time[79] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,809 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112710] time[99] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,898 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112810] time[88] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,969 INFO  [                 org.perf4j.TimingLogger] (      main) start[1348928112899] time[70] tag[ome.io.nio.SimpleBackOff.256X256]
2012-09-29 10:15:12,982 INFO  [                ome.io.nio.PixelsService] (      main) PixelsService(path=/OMERO/, resolver=ome.services.OmeroFilePathResolver@115c1a5, backoff=ome.io.nio.SimpleBackOff(factor=85.0), sizes=ome.io.nio.ConfiguredTileSizes(w=256,h=256,W=3192,H=3192))
2012-09-29 10:15:14,282 INFO  [    ome.services.fulltext.FullTextThread] (      main) Initializing Full-Text Indexer
2012-09-29 10:15:14,326 INFO  [  ome.services.pixeldata.PixelDataThread] (      main) Initializing PixelDataThread (create events only)
2012-09-29 10:15:14,841 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Zip
2012-09-29 10:15:14,842 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: APNG
2012-09-29 10:15:14,844 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: PCX
2012-09-29 10:15:14,846 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Ivision
2012-09-29 10:15:14,849 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: GatanDM2
2012-09-29 10:15:14,863 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: L2D
2012-09-29 10:15:14,864 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Companion/L2D
2012-09-29 10:15:14,924 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: FEI
2012-09-29 10:15:14,925 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: NAF
2012-09-29 10:15:14,926 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: MINC
2012-09-29 10:15:14,928 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: MRW
2012-09-29 10:15:14,928 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: TillVision
2012-09-29 10:15:14,929 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Companion/TillVision
2012-09-29 10:15:14,930 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: ARF
2012-09-29 10:15:14,938 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Cellomics
2012-09-29 10:15:14,940 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: LiFlim
2012-09-29 10:15:14,941 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Targa
2012-09-29 10:15:14,941 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: OxfordInstruments
2012-09-29 10:15:14,943 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: VGSAM
2012-09-29 10:15:14,944 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: HIS
2012-09-29 10:15:14,945 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: WATOP
2012-09-29 10:15:14,945 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Seiko
2012-09-29 10:15:14,946 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Topometrix
2012-09-29 10:15:14,947 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: UBM
2012-09-29 10:15:14,948 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Quesant
2012-09-29 10:15:14,949 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: BioRadGel
2012-09-29 10:15:14,949 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: RHK
2012-09-29 10:15:14,950 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: MolecularImaging
2012-09-29 10:15:14,951 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: CellWorx
2012-09-29 10:15:14,952 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Ecat7
2012-09-29 10:15:14,978 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: VarianFDF
2012-09-29 10:15:14,979 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: AIM
2012-09-29 10:15:14,980 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: InCell3000
2012-09-29 10:15:14,981 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Spider
2012-09-29 10:15:14,982 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Volocity
2012-09-29 10:15:14,983 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Imagic
2012-09-29 10:15:14,984 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: HamamatsuVMS
2012-09-29 10:15:14,985 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: CellSens
2012-09-29 10:15:14,986 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: INR
2012-09-29 10:15:14,987 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Kodak
2012-09-29 10:15:14,989 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: VolocityClipping
2012-09-29 10:15:14,991 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: ZeissCZI
2012-09-29 10:15:14,993 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: SIF
2012-09-29 10:15:14,994 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: NDPIS
2012-09-29 10:15:14,994 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Povray
2012-09-29 10:15:14,995 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: IMOD
2012-09-29 10:15:14,996 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: JEOL
2012-09-29 10:15:14,997 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Nifti
2012-09-29 10:15:14,998 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Companion/Nifti
2012-09-29 10:15:14,999 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Analyze
2012-09-29 10:15:15,000 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Companion/Analyze
2012-09-29 10:15:15,001 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: APL
2012-09-29 10:15:15,002 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Companion/APL
2012-09-29 10:15:15,029 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Amira
2012-09-29 10:15:15,030 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Scanr
2012-09-29 10:15:15,031 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Companion/Scanr
2012-09-29 10:15:15,032 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: BD
2012-09-29 10:15:15,033 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Unisoku
2012-09-29 10:15:15,034 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: PDS
2012-09-29 10:15:15,037 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Fuji
2012-09-29 10:15:15,040 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Operetta
2012-09-29 10:15:15,042 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: JPEG2000
2012-09-29 10:15:15,043 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: JPX
2012-09-29 10:15:15,062 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Imacon
2012-09-29 10:15:15,063 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: LEO
2012-09-29 10:15:15,064 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: JPK
2012-09-29 10:15:15,065 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: NDPI
2012-09-29 10:15:15,108 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: PyramidTiff
2012-09-29 10:15:15,112 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: NikonTiff
2012-09-29 10:15:15,112 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: PhotoshopTiff
2012-09-29 10:15:15,113 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: FEITiff
2012-09-29 10:15:15,114 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: SimplePCITiff
2012-09-29 10:15:15,115 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: NikonElementsTiff
2012-09-29 10:15:15,115 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Trestle
2012-09-29 10:15:15,116 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: SIS
2012-09-29 10:15:15,117 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: DNG
2012-09-29 10:15:15,118 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: ZeissTIFF
2012-09-29 10:15:15,119 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: LeicaSCN
2012-09-29 10:15:15,119 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: TiffDelegate
2012-09-29 10:15:15,120 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Text
2012-09-29 10:15:15,121 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Burleigh
2012-09-29 10:15:15,122 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: SMCamera
2012-09-29 10:15:15,123 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: SBIG
2012-09-29 10:15:15,124 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: HRDGDF
2012-09-29 10:15:15,125 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Hitachi
2012-09-29 10:15:15,126 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: Bruker
2012-09-29 10:15:15,126 INFO  [           ome.services.util.DBEnumCheck] (      main) Added format: CanonRaw
2012-09-29 10:15:15,687 INFO  [    ome.services.util.ServerVersionCheck] (      main) -------------------------------------------------
2012-09-29 10:15:15,687 INFO  [    ome.services.util.ServerVersionCheck] (      main) OMERO Version: 4.4.4-ice34-b3099 Ready.

pkMyt1
 
Posts: 12
Joined: Sat Sep 29, 2012 2:07 am

Re: DB Connection error with Postgresql 9.1

Postby jmoore » Thu Oct 04, 2012 8:13 am

The last error "Caused by: org.postgresql.util.PSQLException: ERROR: permission denied for relation dbpatch" would seem to say something went wrong while setting up the users for the DB. Is that possible? Who's listed as owner of the DB when you do
Code: Select all
psql -l
?

Who's listed as the owner of the tables when you do
Code: Select all
psql omero -c '\d'
?

Cheers,
~Josh
User avatar
jmoore
Site Admin
 
Posts: 1591
Joined: Fri May 22, 2009 1:29 pm
Location: Germany


Return to Installation and Deployment

Who is online

Users browsing this forum: No registered users and 1 guest