Error while deploying platform for the first time

310 views

Skip to first unread message

Santiago Gonzalez Toral

unread,

Nov 17, 2017, 12:46:16 PM11/17/17

to Student Success Plan Developer Community

When running `SSP_CONFIGDIR=/usr/local/ssp/ssp-local ant -Dmaven.test.skip=true clean initportal`, the following exception is being thrown:

```

[java] -------------------------------------------------------------------

[java] GMS: address=ee0cd82bf263-29993, cluster=uPortal.cacheManager, physical address=172.17.0.2:38999

[java] -------------------------------------------------------------------

[java] INFO [24:37.861] Initializing ExecutorService 'uPortalTaskScheduler'

[java] INFO [24:40.549] Building JPA container EntityManagerFactory for persistence unit 'RawEventsDb'

[java] WARN [24:41.447] HHH000342: Could not obtain connection to query metadata : The connection attempt failed.

[java] INFO [24:41.468] Shutting down ExecutorService 'uPortalTaskScheduler'

[java] Exception in thread "main" org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'messageSource' defined in class path resource [properties/contexts/mvcContext.xml]: Cannot create inner bean 'org.jasig.portal.i18n.DatabaseMessageSource#442675e1' of type [org.jasig.portal.i18n.DatabaseMessageSource] while setting bean property 'parentMessageSource'; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'org.jasig.portal.i18n.DatabaseMessageSource#442675e1': Injection of autowired dependencies failed; nested exception is org.springframework.beans.factory.BeanCreationException: Could not autowire method: public void org.jasig.portal.i18n.DatabaseMessageSource.setMessageDao(org.jasig.portal.i18n.dao.IMessageDao); nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'jpaMessageDao': Injection of persistence dependencies failed; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'RawEventsDbEntityManagerFactory' defined in class path resource [properties/contexts/eventPersistenceContext.xml]: Invocation of init method failed; nested exception is javax.persistence.PersistenceException: [PersistenceUnit: RawEventsDb] Unable to build EntityManagerFactory

[java] at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveInnerBean(BeanDefinitionValueResolver.java:287)

[java] at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:122)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyPropertyValues(AbstractAutowireCapableBeanFactory.java:1417)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1158)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:519)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:458)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:296)

[java] at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:293)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:198)

[java] at org.springframework.context.support.AbstractApplicationContext.initMessageSource(AbstractApplicationContext.java:786)

[java] at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:467)

[java] at org.jasig.portal.spring.PortalApplicationContextLocator$PortalApplicationContextCreator.createSingleton(PortalApplicationContextLocator.java:189)

[java] at org.jasig.portal.spring.PortalApplicationContextLocator$PortalApplicationContextCreator.createSingleton(PortalApplicationContextLocator.java:171)

[java] at org.jasig.portal.utils.threading.SingletonDoubleCheckedCreator.create(SingletonDoubleCheckedCreator.java:55)

[java] at org.jasig.portal.utils.threading.DoubleCheckedCreator.get(DoubleCheckedCreator.java:111)

[java] at org.jasig.portal.spring.PortalApplicationContextLocator.getApplicationContext(PortalApplicationContextLocator.java:146)

[java] at org.jasig.portal.spring.locator.PortalDbLocator.getPortalDb(PortalDbLocator.java:45)

[java] at org.jasig.portal.RDBMServices.getDataSource(RDBMServices.java:139)

[java] at org.jasig.portal.RDBMServices.getConnection(RDBMServices.java:191)

[java] at org.jasig.portal.RDBMServices.getConnection(RDBMServices.java:174)

[java] at org.jasig.portal.tools.DbTest.main(DbTest.java:41)

[java] Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'org.jasig.portal.i18n.DatabaseMessageSource#442675e1': Injection of autowired dependencies failed; nested exception is org.springframework.beans.factory.BeanCreationException: Could not autowire method: public void org.jasig.portal.i18n.DatabaseMessageSource.setMessageDao(org.jasig.portal.i18n.dao.IMessageDao); nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'jpaMessageDao': Injection of persistence dependencies failed; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'RawEventsDbEntityManagerFactory' defined in class path resource [properties/contexts/eventPersistenceContext.xml]: Invocation of init method failed; nested exception is javax.persistence.PersistenceException: [PersistenceUnit: RawEventsDb] Unable to build EntityManagerFactory

[java] at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessPropertyValues(AutowiredAnnotationBeanPostProcessor.java:289)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1146)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:519)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:458)

[java] at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveInnerBean(BeanDefinitionValueResolver.java:276)

[java] ... 21 more

[java] Caused by: org.springframework.beans.factory.BeanCreationException: Could not autowire method: public void org.jasig.portal.i18n.DatabaseMessageSource.setMessageDao(org.jasig.portal.i18n.dao.IMessageDao); nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'jpaMessageDao': Injection of persistence dependencies failed; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'RawEventsDbEntityManagerFactory' defined in class path resource [properties/contexts/eventPersistenceContext.xml]: Invocation of init method failed; nested exception is javax.persistence.PersistenceException: [PersistenceUnit: RawEventsDb] Unable to build EntityManagerFactory

[java] at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredMethodElement.inject(AutowiredAnnotationBeanPostProcessor.java:604)

[java] at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:87)

[java] at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessPropertyValues(AutowiredAnnotationBeanPostProcessor.java:286)

[java] ... 25 more

[java] Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'jpaMessageDao': Injection of persistence dependencies failed; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'RawEventsDbEntityManagerFactory' defined in class path resource [properties/contexts/eventPersistenceContext.xml]: Invocation of init method failed; nested exception is javax.persistence.PersistenceException: [PersistenceUnit: RawEventsDb] Unable to build EntityManagerFactory

[java] at org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor.postProcessPropertyValues(PersistenceAnnotationBeanPostProcessor.java:344)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1146)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:519)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:458)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:296)

[java] at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:293)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)

[java] at org.springframework.beans.factory.support.DefaultListableBeanFactory.findAutowireCandidates(DefaultListableBeanFactory.java:917)

[java] at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:860)

[java] at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:775)

[java] at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredMethodElement.inject(AutowiredAnnotationBeanPostProcessor.java:561)

[java] ... 27 more

[java] Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'RawEventsDbEntityManagerFactory' defined in class path resource [properties/contexts/eventPersistenceContext.xml]: Invocation of init method failed; nested exception is javax.persistence.PersistenceException: [PersistenceUnit: RawEventsDb] Unable to build EntityManagerFactory

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1512)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:521)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:458)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:296)

[java] at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:293)

[java] at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:194)

[java] at org.springframework.orm.jpa.EntityManagerFactoryUtils.findEntityManagerFactory(EntityManagerFactoryUtils.java:101)

[java] at org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor.findNamedEntityManagerFactory(PersistenceAnnotationBeanPostProcessor.java:515)

[java] at org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor.findEntityManagerFactory(PersistenceAnnotationBeanPostProcessor.java:497)

[java] at org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor$PersistenceElement.resolveEntityManager(PersistenceAnnotationBeanPostProcessor.java:663)

[java] at org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor$PersistenceElement.getResourceToInject(PersistenceAnnotationBeanPostProcessor.java:634)

[java] at org.springframework.beans.factory.annotation.InjectionMetadata$InjectedElement.inject(InjectionMetadata.java:164)

[java] at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:87)

[java] at org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor.postProcessPropertyValues(PersistenceAnnotationBeanPostProcessor.java:341)

[java] ... 38 more

[java] Caused by: javax.persistence.PersistenceException: [PersistenceUnit: RawEventsDb] Unable to build EntityManagerFactory

[java] at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:915)

[java] at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:890)

[java] at org.hibernate.ejb.HibernatePersistence.createContainerEntityManagerFactory(HibernatePersistence.java:74)

[java] at org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean.createNativeEntityManagerFactory(LocalContainerEntityManagerFactoryBean.java:290)

[java] at org.jasig.portal.jpa.EventingLocalContainerEntityManagerFactoryBean.createNativeEntityManagerFactory(EventingLocalContainerEntityManagerFactoryBean.java:61)

[java] at org.springframework.orm.jpa.AbstractEntityManagerFactoryBean.afterPropertiesSet(AbstractEntityManagerFactoryBean.java:310)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1571)

[java] at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1509)

[java] ... 52 more

[java] Caused by: org.hibernate.HibernateException: Connection cannot be null when 'hibernate.dialect' not set

[java] at org.hibernate.service.jdbc.dialect.internal.DialectFactoryImpl.determineDialect(DialectFactoryImpl.java:97)

[java] at org.hibernate.service.jdbc.dialect.internal.DialectFactoryImpl.buildDialect(DialectFactoryImpl.java:67)

[java] at org.hibernate.engine.jdbc.internal.JdbcServicesImpl.configure(JdbcServicesImpl.java:170)

[java] at org.hibernate.service.internal.StandardServiceRegistryImpl.configureService(StandardServiceRegistryImpl.java:75)

[java] at org.hibernate.service.internal.AbstractServiceRegistryImpl.initializeService(AbstractServiceRegistryImpl.java:159)

[java] at org.hibernate.service.internal.AbstractServiceRegistryImpl.getService(AbstractServiceRegistryImpl.java:131)

[java] at org.hibernate.cfg.SettingsFactory.buildSettings(SettingsFactory.java:73)

[java] at org.hibernate.cfg.Configuration.buildSettingsInternal(Configuration.java:2279)

[java] at org.hibernate.cfg.Configuration.buildSettings(Configuration.java:2275)

[java] at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1744)

[java] at org.hibernate.ejb.EntityManagerFactoryImpl.<init>(EntityManagerFactoryImpl.java:94)

[java] at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:905)

[java] ... 59 more

```

I set the corresponding the database properties in the `ssp-platform-config.properties` as follows

```

environment.build.hibernate.connection.driver_class=org.postgresql.Driver

environment.build.hibernate.connection.url=jdbc:postgresql://localhost:5432/ssp

environment.build.hibernate.connection.username=sspadmin

environment.build.hibernate.connection.password=sspadmin

environment.build.hibernate.dialect=org.hibernate.dialect.PostgreSQLDialect

```

What could be the problem?

Thank for your help

Drew Wills

unread,

Nov 17, 2017, 2:08:11 PM11/17/17

to ssp...@apereo.org

It looks like SSP cannot connect to the database. Is PostgreSQL started on localhost:5432?

drew

--
You received this message because you are subscribed to the Google Groups "Student Success Plan Developer Community" group.
To unsubscribe from this group and stop receiving emails from it, send an email to ssp-dev+u...@apereo.org.
Visit this group at https://groups.google.com/a/apereo.org/group/ssp-dev/.

Paul Spaude

unread,

Nov 20, 2017, 1:28:13 PM11/20/17

to Santiago Gonzalez Toral, Student Success Plan Developer Community

First, make sure the ssp database is created on the Postgres server and network wise that SSP can reach the database. In this case it appears it's a local installation, so we can eliminate remote connection/server issues. Although Postgres does by default block local connections in some installs. Running psql -d ssp -U sspadmin should ensure Postgres configuration is correct if it works.

Then, make sure the ssp database user has permission to create/delete tables and procedures in Postgres. To be 100% sure run "grant all privileges on database ssp to sspadmin;" or similar.

Next, ssp-config.properties and ssp-platform-config.properties should be in their own folder. The folder path needs be set as an environment variable called SSP_CONFIGDIR. That needs to be available when building SSP-Platform and running Tomcat. If you're running Linux or Mac, it looks like you've done that, so mainly double check permissions on that folder and files. If your using Windows, the command shown won't work. You'll need to set SSP_CONFIGDIR in Environment Variables -> System Environment Variables.

The next step is break the initportal up into separate stages. initportal (which includes intitdb) only needs to run once for a new database; otherwise it overwrites added users or permissions. So, let's make sure that SSP-Platform minus the database stuff can be succesffully built and deployed to Tomcat.

Run this: `SSP_CONFIGDIR=/usr/local/ssp/ssp-local ant -Dmaven.test.skip=true clean deploy-ear`. If that doesn't work, make sure your SSP-Platform code matches with a branch here: https://github.com/Jasig/SSP-Platform. If you have made customizations, consider building the vanilla first and adding the customizations later. The only change you need to make is to create a build.properties (copied from the sample) and fill out the Tomcat location.

If deploy-ear works, then run: `SSP_CONFIGDIR=/usr/local/ssp/ssp-local ant -Dmaven.test.skip=true clean initdb`. If that fails, again re-check permissions, database user permissions and verify the database allows access from localhost/127.0.0.1 in pg_hba_conf or similar Postgres configuration. If it works, your problem has been solved and initportal/initdb won't need to be run on this database again.

Sincerely,

Paul Spaude

From: "Santiago Gonzalez Toral" <hernan...@gmail.com>
To: "Student Success Plan Developer Community" <ssp...@apereo.org>
Sent: Friday, November 17, 2017 10:46:16 AM
Subject: [ssp-dev] Error while deploying platform for the first time

Santiago Gonzalez Toral

unread,

Nov 26, 2017, 8:33:06 PM11/26/17

to Paul Spaude, Student Success Plan Developer Community

Hello,

Thanks for your advice. I was just having an issue with a docker compose definition. Now, I'm able to deploy the application but for some reason it is not working yet. After I logged in using credentials admin/admin, I cannot do anything in the platform. When I do click any menu item (e.g. Manager Users, Reports, MyGPS), it just redirects me to the login screen. One thing I noticed when deploying the platform was that the postgres logs were printing statement execution errors like the following https://pastebin.com/3MHvCfw9 . I am obtaining this output either running initdb or initportal targets.

Thanks in advance for any help or advice you could give me.

Regards,

Santiago

Regards,

Santiago Gonzalez Toral

MSc Web Science and Big Data Analytics

Paul Spaude

unread,

Nov 29, 2017, 4:46:52 PM11/29/17

to Santiago Gonzalez Toral, Student Success Plan Developer Community

The errors in pastebin show the uPortal (SSP-Platform) tables don't exist in the database where SSP is currently working out of. It also might be that the database user doesn't have permission to create tables and thus initdb (which initportal runs) didn't add the 70ish required up_* tables.

First make sure the database user specified in ssp-platform-config.properties has requisite permissions on the database you wish to use. Run grant all privileges on database NAME_GOES_HERE to USER_GOES_HERE; Make sure that the same database and database user is entered into ssp-config.properties and ssp-platform-config.properties. Then run initdb or intiportal again. Verify there are ~76 or so up_* tables in the database and this error should be taken care of.

-Paul

From: "Santiago Gonzalez Toral" <hernan...@gmail.com>

To: "Paul Spaude" <psp...@unicon.net>
Cc: "Student Success Plan Developer Community" <ssp...@apereo.org>
Sent: Sunday, November 26, 2017 6:33:05 PM
Subject: Re: [ssp-dev] Error while deploying platform for the first time

This email has been scanned for spam and viruses by Proofpoint Essentials. Click here to report this email as spam.

Santiago Gonzalez Toral

unread,

Nov 29, 2017, 5:25:29 PM11/29/17

to Paul Spaude, Student Success Plan Developer Community

Tables are being created. Now I noticed the following errors (looks like doing 3 attempts to execute the query) when deploying SSP using initportal:

ERROR: syntax error at or near ")" at character 108

STATEMENT: SELECT COUNT(UP_USER.USER_ID) FROM UP_USER, UP_USER_LAYOUT WHERE UP_USER.USER_ID = UP_USER_LAYOUT.USER_ID(+) AND UP_USER.USER_ID=0

ERROR: syntax error at or near ")" at character 108

STATEMENT: SELECT COUNT(UP_USER.USER_ID) FROM UP_USER, UP_USER_LAYOUT WHERE UP_USER.USER_ID = UP_USER_LAYOUT.USER_ID(+) AND UP_USER.USER_ID=0

ERROR: syntax error at or near ")" at character 108

STATEMENT: SELECT COUNT(UP_USER.user_id) FROM UP_USER, UP_USER_LAYOUT WHERE UP_USER.USER_ID = UP_USER_LAYOUT.USER_ID(+) AND UP_USER.USER_ID=0

The error falls in the "(+)" symbol under the SQL query. Seems that it it trying to execute an outer join using Oracle syntax but I'm using PostgreSQL 9.3.

Paul Spaude

unread,

Nov 29, 2017, 6:06:01 PM11/29/17

to Santiago Gonzalez Toral, Student Success Plan Developer Community

Postgres 9.3 should work although I'm not sure if SSP 2.8+ has ever been tested on that version.

First thing to check is ssp-config.properties mainly that the driver and dialect appear uncommented like below:

db_driver_class=org.postgresql.Driver
db_dialect=org.hibernate.dialect.PostgreSQLDialect

In ssp-platform-config.properties, try changing "environment.build.hibernate.dialect=" to "org.hibernate.dialect.PostgreSQLDialect".

-Paul

From: "Santiago Gonzalez Toral" <hernan...@gmail.com>
To: "Paul Spaude" <psp...@unicon.net>
Cc: "Student Success Plan Developer Community" <ssp...@apereo.org>

Sent: Wednesday, November 29, 2017 3:25:27 PM

Santiago Gonzalez Toral

unread,

Nov 29, 2017, 7:23:34 PM11/29/17

to Paul Spaude, Student Success Plan Developer Community

DB parameters in config files are the same as you mentioned, so I downgrade the PostgreSQL server to v9.1 but SQL query errors are still showing up. I append to this post my config files. I also uploaded a screen recording showing the error I'm experimenting when testing the platform -> https://youtu.be/cyR0kOg6WLg

build.properties

logback.xml

ssp-config.properties

ssp-platform-config.properties

Paul Spaude

unread,

Nov 29, 2017, 8:35:49 PM11/29/17

to Santiago Gonzalez Toral, Student Success Plan Developer Community

I've never seen this error before, so this could be something new whether specific to old Postgres versions or there is an external cause (perhaps driver or jdk or database setting etc.). SSP doesn't control the SQL directly instead queries are created by Hibernate then sent through the JDBC driver. It's possible a hibernate upgrade has made SSP incompatible with older Postgres versions, although I'm hesitant to think that Postgres 9.3 would be a problem since it hasn't reached end of life yet. See https://www.postgresql.org/support/versioning/

I believe it's worked for me on Postgres 9.4, although I haven't used it in awhile. At the moment I know it's working on Postgres 9.6.

Besides changing the hibernate dialect and perhaps updating the Postgres driver (or going back a few versions if you have the latest), there isn't much else that can be done from SSP's end.

If you do decide to change database versions make sure it's the database server changing and not the client tools (psql) or Pgadmin.

-Paul

From: "Santiago Gonzalez Toral" <hernan...@gmail.com>
To: "Paul Spaude" <psp...@unicon.net>
Cc: "Student Success Plan Developer Community" <ssp...@apereo.org>

Sent: Wednesday, November 29, 2017 5:23:32 PM

Santiago Gonzalez Toral

unread,

Nov 30, 2017, 9:07:37 PM11/30/17

to Paul Spaude, Student Success Plan Developer Community

I upgraded my PostgreSQL version to 9.5 as with v9.6, ssp deployment throws a SQL exception due to a missing column on one of the tables from pg_catalog (similar to the error reported in this issue) and now I'm getting an error when trying to authenticate the ssp-platform with admin/admin credentials. You can find a screenshot of what I'm getting attached to this post.

Is there a way to get good logs of what is going on when I'm trying to authenticate? Could you help me with an example file of logback.xml that I can use to achieve this?

Thank you

Santiago

screenshot.png

Santiago Gonzalez Toral

unread,

Dec 7, 2017, 5:33:29 PM12/7/17

to Paul Spaude, Student Success Plan Developer Community

After some attempts I realised that ssp instance is logging the following errors when deploying tomcat. As you can see, I'm getting errors related to the missing 'dbo' schema (from MSSQL), but I'm using a PostgreSQL database.

DEV: 21:18:25.174 [localhost-startStop-1] INFO o.s.s.c.ThreadPoolTaskScheduler - Initializing ExecutorService 'sspScheduler'

DEV: 21:18:27.167 [localhost-startStop-1] WARN o.s.w.c.s.XmlWebApplicationContext - Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'earlyAlertResponseReminderRecipientsConfigImpl': Unsatisfied dependency expressed through field 'configService': Error creating bean with name 'configServiceImpl': Unsatisfied dependency expressed through field 'dao': Error creating bean with name 'configDao': Unsatisfied dependency expressed through field 'sessionFactory': Error creating bean with name 'liquibase' defined in ServletContext resource [/WEB-INF/spring/app-config.xml]: Invocation of init method failed; nested exception is liquibase.exception.LockException: liquibase.exception.DatabaseException: Error executing SQL CREATE TABLE dbo.databasechangeloglock (ID INT NOT NULL, LOCKED BOOLEAN NOT NULL, LOCKGRANTED TIMESTAMP WITHOUT TIME ZONE, LOCKEDBY VARCHAR(255), CONSTRAINT PK_DATABASECHANGELOGLOCK PRIMARY KEY (ID)); on jdbc:postgresql://postgresssp:5432/sspINSERT INTO dbo.databasechangeloglock (ID, LOCKED) VALUES (1, FALSE): ERROR: schema "dbo" does not exist

Position: 14; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'liquibase' defined in ServletContext resource [/WEB-INF/spring/app-config.xml]: Invocation of init method failed; nested exception is liquibase.exception.LockException: liquibase.exception.DatabaseException: Error executing SQL CREATE TABLE dbo.databasechangeloglock (ID INT NOT NULL, LOCKED BOOLEAN NOT NULL, LOCKGRANTED TIMESTAMP WITHOUT TIME ZONE, LOCKEDBY VARCHAR(255), CONSTRAINT PK_DATABASECHANGELOGLOCK PRIMARY KEY (ID)); on jdbc:postgresql://postgresssp:5432/sspINSERT INTO dbo.databasechangeloglock (ID, LOCKED) VALUES (1, FALSE): ERROR: schema "dbo" does not exist

Position: 14; nested exception is org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'configDao': Unsatisfied dependency expressed through field 'sessionFactory': Error creating bean with name 'liquibase' defined in ServletContext resource [/WEB-INF/spring/app-config.xml]: Invocation of init method failed; nested exception is liquibase.exception.LockException: liquibase.exception.DatabaseException: Error executing SQL CREATE TABLE dbo.databasechangeloglock (ID INT NOT NULL, LOCKED BOOLEAN NOT NULL, LOCKGRANTED TIMESTAMP WITHOUT TIME ZONE, LOCKEDBY VARCHAR(255), CONSTRAINT PK_DATABASECHANGELOGLOCK PRIMARY KEY (ID)); on jdbc:postgresql://postgresssp:5432/sspINSERT INTO dbo.databasechangeloglock (ID, LOCKED) VALUES (1, FALSE): ERROR: schema "dbo" does not exist

Position: 14; nested exception is org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'configServiceImpl': Unsatisfied dependency expressed through field 'dao': Error creating bean with name 'configDao': Unsatisfied dependency expressed through field 'sessionFactory': Error creating bean with name 'liquibase' defined in ServletContext resource [/WEB-INF/spring/app-config.xml]: Invocation of init method failed; nested exception is liquibase.exception.LockException: liquibase.exception.DatabaseException: Error executing SQL CREATE TABLE dbo.databasechangeloglock (ID INT NOT NULL, LOCKED BOOLEAN NOT NULL, LOCKGRANTED TIMESTAMP WITHOUT TIME ZONE, LOCKEDBY VARCHAR(255), CONSTRAINT PK_DATABASECHANGELOGLOCK PRIMARY KEY (ID)); on jdbc:postgresql://postgresssp:5432/sspINSERT INTO dbo.databasechangeloglock (ID, LOCKED) VALUES (1, FALSE): ERROR: schema "dbo" does not exist

Position: 14

DEV: 21:18:27.169 [localhost-startStop-1] INFO o.s.s.c.ThreadPoolTaskScheduler - Shutting down ExecutorService 'sspScheduler'

DEV: 21:18:27.185 [localhost-startStop-1] ERROR o.s.web.context.ContextLoader - Context initialization failed

org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'earlyAlertResponseReminderRecipientsConfigImpl': Unsatisfied dependency expressed through field 'configService': Error creating bean with name 'configServiceImpl': Unsatisfied dependency expressed through field 'dao': Error creating bean with name 'configDao': Unsatisfied dependency expressed through field 'sessionFactory': Error creating bean with name 'liquibase' defined in ServletContext resource [/WEB-INF/spring/app-config.xml]: Invocation of init method failed; nested exception is liquibase.exception.LockException: liquibase.exception.DatabaseException: Error executing SQL CREATE TABLE dbo.databasechangeloglock (ID INT NOT NULL, LOCKED BOOLEAN NOT NULL, LOCKGRANTED TIMESTAMP WITHOUT TIME ZONE, LOCKEDBY VARCHAR(255), CONSTRAINT PK_DATABASECHANGELOGLOCK PRIMARY KEY (ID)); on jdbc:postgresql://postgresssp:5432/sspINSERT INTO dbo.databasechangeloglock (ID, LOCKED) VALUES (1, FALSE): ERROR: schema "dbo" does not exist

Position: 14

at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:569) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:88) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessPropertyValues(AutowiredAnnotationBeanPostProcessor.java:349) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1214) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:543) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:482) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:197) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:776) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:861) ~[spring-context-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:541) ~[spring-context-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.web.context.ContextLoader.configureAndRefreshWebApplicationContext(ContextLoader.java:444) ~[spring-web-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:326) ~[spring-web-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:107) [spring-web-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4745) [catalina.jar:8.5.23]

at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5207) [catalina.jar:8.5.23]

at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) [catalina.jar:8.5.23]

at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:752) [catalina.jar:8.5.23]

at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:728) [catalina.jar:8.5.23]

at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734) [catalina.jar:8.5.23]

at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1144) [catalina.jar:8.5.23]

at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1878) [catalina.jar:8.5.23]

at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_151]

at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_151]

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_151]

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_151]

at java.lang.Thread.run(Thread.java:748) [na:1.8.0_151]

Caused by: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'configServiceImpl': Unsatisfied dependency expressed through field 'dao': Error creating bean with name 'configDao': Unsatisfied dependency expressed through field 'sessionFactory': Error creating bean with name 'liquibase' defined in ServletContext resource [/WEB-INF/spring/app-config.xml]: Invocation of init method failed; nested exception is liquibase.exception.LockException: liquibase.exception.DatabaseException: Error executing SQL CREATE TABLE dbo.databasechangeloglock (ID INT NOT NULL, LOCKED BOOLEAN NOT NULL, LOCKGRANTED TIMESTAMP WITHOUT TIME ZONE, LOCKEDBY VARCHAR(255), CONSTRAINT PK_DATABASECHANGELOGLOCK PRIMARY KEY (ID)); on jdbc:postgresql://postgresssp:5432/sspINSERT INTO dbo.databasechangeloglock (ID, LOCKED) VALUES (1, FALSE): ERROR: schema "dbo" does not exist

Position: 14

at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:88) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1214) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:543) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:482) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:202) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:207) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.DefaultListableBeanFactory.findAutowireCandidates(DefaultListableBeanFactory.java:1214) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1054) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1019) ~[spring-beans-4.3.2.RELEASE.jar:4.3.2.RELEASE]

... 28 common frames omitted

Caused by: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'configDao': Unsatisfied dependency expressed through field 'sessionFactory': Error creating bean with name 'liquibase' defined in ServletContext resource [/WEB-INF/spring/app-config.xml]: Invocation of init method failed; nested exception is liquibase.exception.LockException: liquibase.exception.DatabaseException: Error executing SQL CREATE TABLE dbo.databasechangeloglock (ID INT NOT NULL, LOCKED BOOLEAN NOT NULL, LOCKGRANTED TIMESTAMP WITHOUT TIME ZONE, LOCKEDBY VARCHAR(255), CONSTRAINT PK_DATABASECHANGELOGLOCK PRIMARY KEY (ID)); on jdbc:postgresql://postgresssp:5432/sspINSERT INTO dbo.databasechangeloglock (ID, LOCKED) VALUES (1, FALSE): ERROR: schema "dbo" does not exist