#1 2020-03-08 22:16:15

Jlux33
Member
Registered: 2020-03-08

New Installation Startup Failing

New install on CentOS 8 with Tomcat 8 and MySQL.  Database is created and configured files set.  When launching URL, test connect to DB is failing.

How can I get this report server running?

I've made some progress.  The ReportServer installation comes with a 5.x MySQL JDBC driver.  I am running MySQL 8.0.19.  I copied in the newer JDBC driver and now am able to connect to MySQL.  However, it now says the RS_ACE table missing.  It is definitely there.

Here is the catalina.out logs.
20:38:49.510 INFO  n.datenwerke.rs.EnvironmentValidator -




  _____                       _    _____                         
|  __ \                     | |  / ____|                         
| |__) |___ _ __   ___  _ __| |_| (___   ___ _ ____   _____ _ __
|  _  // _ \ '_ \ / _ \| '__| __|\___ \ / _ \ '__\ \ / / _ \ '__|
| | \ \  __/ |_) | (_) | |  | |_ ____) |  __/ |   \ V /  __/ |   
|_|  \_\___| .__/ \___/|_|   \__|_____/ \___|_|    \_/ \___|_|   
            | |                                                   
            |_|                                                   

Version: RS3.1.1-6020 2020-01-28-16-18-55
Code Version: 2020-01-28-16-14-09
Java Version: Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.131-b11 (1.8)
VM Args: -Djava.util.logging.config.file=/opt/tomcat/conf/logging.properties -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.security.egd=file:///dev/urandom -Djdk.tls.ephemeralDHKeySize=2048 -Djava.protocol.handler.pkgs=org.apache.catalina.webresources -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 -Xms512M -Xmx1024M -XX:+UseParallelGC -Dignore.endorsed.dirs= -Dcatalina.base=/opt/tomcat -Dcatalina.home=/opt/tomcat -Djava.io.tmpdir=/opt/tomcat/temp
rs.configdir: Not Configured

### DB Config ###
hibernate.dialect: net.datenwerke.rs.utils.hibernate.MySQL5Dialect (OK)
hibernate.connection.driver_class: com.mysql.jdbc.Driver (OK)
hibernate.connection.url: jdbc:mysql://localhost:3306/reportserver?useSSL=false&serverTimezone=UTC&allowPublicKeyRetrieval=true (OK)
hibernate.connection.username: rs
hibernate.connection.password: ***********
hibernate.default_schema: reportserver

Connection Test: OK
Schema Version: RS3.0-15

20:38:49.516 INFO  n.datenwerke.rs.EnvironmentValidator - Validating database schema...
20:38:51.543 ERROR n.datenwerke.rs.EnvironmentValidator - Schema-validation: missing table [RS_ACE]
20:38:51.543 ERROR n.d.rs.ReportServerServiceConfig - ReportServer encountered an error while validating its environment. Startup interrupted.
08-Mar-2020 20:38:51.720 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/ROOT] has finished in [9,604] ms
08-Mar-2020 20:38:51.720 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/docs]
08-Mar-2020 20:38:51.727 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/docs] has finished in [7] ms
08-Mar-2020 20:38:51.727 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/examples]
08-Mar-2020 20:38:51.809 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/examples] has finished in [82] ms
08-Mar-2020 20:38:51.809 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/host-manager]
08-Mar-2020 20:38:51.820 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/host-manager] has finished in [11] ms
08-Mar-2020 20:38:51.820 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/manager]
08-Mar-2020 20:38:51.829 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/manager] has finished in [9] ms
08-Mar-2020 20:38:51.832 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
08-Mar-2020 20:38:51.841 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 9752 ms


This is the output of logging in on localhost and selecting contents of the RS_ACE table.

[root@localhost ~]# mysql -u rs -p
Enter password:
Welcome to the MySQL monitor.  Commands end with ; or \g.
Your MySQL connection id is 67
Server version: 8.0.17 Source distribution

Copyright (c) 2000, 2019, Oracle and/or its affiliates. All rights reserved.

Oracle is a registered trademark of Oracle Corporation and/or its
affiliates. Other names may be trademarks of their respective
owners.

Type 'help;' or '\h' for help. Type '\c' to clear the current input statement.

mysql> use reportserver;
Reading table information for completion of table and column names
You can turn off this feature to get a quicker startup with -A

Database changed
mysql> select * from RS_ACE;
Empty set (0.00 sec)

mysql>

Last edited by Jlux33 (2020-03-09 00:57:58)

Offline

#2 2020-03-09 09:19:20

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jlux33,

how did you install reportserver exactly, manually or with a bitnami package?

Regards,
Eduardo

Offline

#3 2020-03-09 10:19:17

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

Manual install.   It is an existing server and I prefer to use already working tomcat and MySQL.

Offline

#4 2020-03-10 11:50:57

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jlux33,

MySQL 8 is not yet supported officially. I think this error is about Hibernate not recognizing your MySQL 8 installation, but I am not yet sure, until we test this with MySQL 8. We have ticket RS-3029 for MySQL 8 support.

Until then, please use MySQL 5 or any other supported database.

Regards,
Eduardo

Offline

#5 2020-03-10 12:42:31

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

Thank you.

Any timing on MySQL 8?

Offline

#6 2020-03-10 13:38:29

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jlux33,

as the ticket is currently in the development queue, there are not timing details available yet.

Regards,
Eduardo

Offline

#7 2020-03-10 15:21:56

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

Thank you.

I have installed MySQL 5.7 and the catalino.out is reporting OK on startup.  However, this is the response I am getting on the URL.
HTTP Status 404 – Not Found
Type Status Report

Message Not found

Description The origin server did not find a current representation for the target resource or is not willing to disclose that one exists.

Apache Tomcat/8.5.51

Offline

#8 2020-03-10 15:35:27

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

This is my catalino.out file:

Version: RS3.1.1-6020 2020-01-28-16-18-55
Code Version: 2020-01-28-16-14-09
Java Version: Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.131-b11 (1.8)
VM Args: -Djava.util.logging.config.file=/opt/tomcat/conf/logging.properties -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.security.egd=file:///dev/urandom -Djdk.tls.ephemeralDHKeySize=2048 -Djava.protocol.handler.pkgs=org.apache.catalina.webresources -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 -Xms512M -Xmx1024M -XX:+UseParallelGC -Dignore.endorsed.dirs= -Dcatalina.base=/opt/tomcat -Dcatalina.home=/opt/tomcat -Djava.io.tmpdir=/opt/tomcat/temp
rs.configdir: Not Configured

### DB Config ###
hibernate.dialect: net.datenwerke.rs.utils.hibernate.MySQL5Dialect (OK)
hibernate.connection.driver_class: com.mysql.jdbc.Driver (OK)
hibernate.connection.url: jdbc:mysql://localhost:3306/reportserver?useSSL=false&serverTimezone=UTC (OK)
hibernate.connection.username: rs
hibernate.connection.password: ***********
hibernate.default_schema: reportserver

Connection Test: OK
Schema Version: RS3.0-15

11:30:09.827 INFO  n.datenwerke.rs.EnvironmentValidator - Validating database schema...
11:30:13.940 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.AdminResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.AdminResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.BasicRepositoryResource2
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.BasicRepositoryResource2
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.DataSourceResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.DataSourceResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.ExporterResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.ExporterResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.FilterRepositoryResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.FilterRepositoryResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.InfoResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.InfoResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.License
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.License
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.OlapDiscoverResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.OlapDiscoverResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.Query2Resource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.Query2Resource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.SessionResource
11:30:13.941 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.SessionResource
11:30:13.942 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.SaikuI18nResource
11:30:13.942 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.SaikuI18nResource
11:30:13.942 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.StatisticsResource
11:30:13.942 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.StatisticsResource
11:30:16.371 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile scheduler/scheduler.cf could not be loaded. Default values are in effect.
11:30:16.373 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile exportfilemd/pdfexport.cf could not be loaded. Default values are in effect.
11:30:16.373 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile exportfilemd/pdfexport.cf could not be loaded. Default values are in effect.
11:30:16.453 INFO  n.d.r.s.s.saiku.ThinQueryServiceImpl - loaded thinqueryservice
11:30:17.728 ERROR org.hibernate.AssertionFailure - HHH000099: an assertion failure occured (this may indicate a bug in Hibernate, but is more likely due to unsafe use of the session): org.hibernate.AssertionFailure: Table reportserver.RS_PARAMETER_DEFINITION not found
10-Mar-2020 11:30:17.730 SEVERE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal One or more Filters failed to start. Full details will be found in the appropriate container log file
10-Mar-2020 11:30:17.730 SEVERE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal Context [] startup failed due to previous errors
10-Mar-2020 11:30:17.750 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesJdbc The web application [ROOT] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
10-Mar-2020 11:30:17.753 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
com.mysql.jdbc.AbandonedConnectionCleanupThread.run(AbandonedConnectionCleanupThread.java:43)
10-Mar-2020 11:30:17.753 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81iogb8i1r5ro7c|78def9c]-AdminTaskTimer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.util.TimerThread.mainLoop(Timer.java:552)
java.util.TimerThread.run(Timer.java:505)
10-Mar-2020 11:30:17.753 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81iogb8i1r5ro7c|78def9c]-HelperThread-#0] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
10-Mar-2020 11:30:17.754 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81iogb8i1r5ro7c|78def9c]-HelperThread-#1] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
10-Mar-2020 11:30:17.754 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81iogb8i1r5ro7c|78def9c]-HelperThread-#2] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
10-Mar-2020 11:30:17.754 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [com.google.inject.internal.util.$Finalizer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
com.google.inject.internal.util.$Finalizer.run(Finalizer.java:114)
10-Mar-2020 11:30:17.760 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Thread-9] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
com.google.inject.persist.jpa.JpaPersistService.begin(JpaPersistService.java:70)
com.google.inject.persist.jpa.JpaPersistService.get(JpaPersistService.java:50)
com.google.inject.persist.jpa.JpaPersistService.get(JpaPersistService.java:34)
com.google.inject.internal.BoundProviderFactory.get(BoundProviderFactory.java:55)
com.google.inject.internal.InjectorImpl$4$1.call(InjectorImpl.java:978)
com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1024)
com.google.inject.internal.InjectorImpl$4.get(InjectorImpl.java:974)
com.google.inject.internal.InjectorImpl.getInstance(InjectorImpl.java:1013)
net.datenwerke.rs.utils.eventlogger.jpa.JpaEventLoggerStartup$1.run(JpaEventLoggerStartup.java:32)
java.lang.Thread.run(Thread.java:748)
10-Mar-2020 11:30:17.761 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Thread-10] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Thread.sleep(Native Method)
net.datenwerke.gf.service.lateinit.LateInitStartup$1.run(LateInitStartup.java:41)
java.lang.Thread.run(Thread.java:748)
10-Mar-2020 11:30:17.761 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81iogb8i1r5ro7c|4066de44]-AdminTaskTimer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.util.TimerThread.mainLoop(Timer.java:552)
java.util.TimerThread.run(Timer.java:505)
10-Mar-2020 11:30:17.761 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81iogb8i1r5ro7c|4066de44]-HelperThread-#0] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
10-Mar-2020 11:30:17.761 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81iogb8i1r5ro7c|4066de44]-HelperThread-#1] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
10-Mar-2020 11:30:17.761 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81iogb8i1r5ro7c|4066de44]-HelperThread-#2] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
10-Mar-2020 11:30:17.769 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/ROOT] has finished in [15,304] ms
10-Mar-2020 11:30:17.769 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/docs]
10-Mar-2020 11:30:17.783 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/docs] has finished in [13] ms
10-Mar-2020 11:30:17.783 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/examples]
10-Mar-2020 11:30:17.913 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/examples] has finished in [130] ms
10-Mar-2020 11:30:17.913 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/host-manager]
10-Mar-2020 11:30:17.924 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/host-manager] has finished in [11] ms
10-Mar-2020 11:30:17.936 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/manager]
10-Mar-2020 11:30:17.945 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/manager] has finished in [9] ms
10-Mar-2020 11:30:17.954 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
10-Mar-2020 11:30:17.960 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 15518 ms

Offline

#9 2020-03-10 15:36:31

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

You can see this line in the log:

11:30:17.728 ERROR org.hibernate.AssertionFailure - HHH000099: an assertion failure occured (this may indicate a bug in Hibernate, but is more likely due to unsafe use of the session): org.hibernate.AssertionFailure: Table reportserver.RS_PARAMETER_DEFINITION not found

However, the table exists.

Offline

#10 2020-03-11 08:04:52

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jlux33,

this may be good news, as you get basically the same error as with MySQL 8: a table is not found which is there.

ReportServer works definitely with MySQL 5, we have a test installation with 5.7.23. So there may be some issue with your DB connection or with your mysql user's rights. Can you test with a mysql sa-user ?

Regards,
Eduardo

Offline

#11 2020-03-11 08:06:31

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jlux33,

here is an installation guide, it is written with Postgres, but maybe it helps: https://reportserver.net/en/tutorials/i … /#postgres

$ sudo -u postgres  createuser -P -s -e reportserver
Enter password for new role: reportserver
Enter it again: reportserver
CREATE ROLE reportserver PASSWORD 'md5d551331e985d0bc05820de8666e79f60' SUPERUSER CREATEDB CREATEROLE INHERIT LOGIN;

$ sudo -u postgres psql
GRANT ALL PRIVILEGES ON DATABASE reportserver TO reportserver;
CTRL+D

Regards,
Eduardo

Offline

#12 2020-03-11 23:17:09

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

I have already reviewed the installation guide.  It was helpful to get me this far.  I have taken all those steps on the MySQL instance and the rs user has appropriate permissions.

[mysql> show grants for 'rs'@'localhost';
+--------------------------------------------------------------+
| Grants for rs@localhost                                      |
+--------------------------------------------------------------+
| GRANT USAGE ON *.* TO 'rs'@'localhost'                       |
| GRANT ALL PRIVILEGES ON `reportserver`.* TO 'rs'@'localhost' |
+--------------------------------------------------------------+
2 rows in set (0.00 sec)

I also tried setting the persistence.properties file to use the root account and get the same problem.  See log:

19:16:29.051 INFO  n.datenwerke.rs.EnvironmentValidator -




  _____                       _    _____                         
|  __ \                     | |  / ____|                         
| |__) |___ _ __   ___  _ __| |_| (___   ___ _ ____   _____ _ __
|  _  // _ \ '_ \ / _ \| '__| __|\___ \ / _ \ '__\ \ / / _ \ '__|
| | \ \  __/ |_) | (_) | |  | |_ ____) |  __/ |   \ V /  __/ |   
|_|  \_\___| .__/ \___/|_|   \__|_____/ \___|_|    \_/ \___|_|   
            | |                                                   
            |_|                                                   

Version: RS3.1.1-6020 2020-01-28-16-18-55
Code Version: 2020-01-28-16-14-09
Java Version: Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.131-b11 (1.8)
VM Args: -Djava.util.logging.config.file=/opt/tomcat/conf/logging.properties -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.security.egd=file:///dev/urandom -Djdk.tls.ephemeralDHKeySize=2048 -Djava.protocol.handler.pkgs=org.apache.catalina.webresources -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 -Xms512M -Xmx1024M -XX:+UseParallelGC -Dignore.endorsed.dirs= -Dcatalina.base=/opt/tomcat -Dcatalina.home=/opt/tomcat -Djava.io.tmpdir=/opt/tomcat/temp
rs.configdir: Not Configured

### DB Config ###
hibernate.dialect: net.datenwerke.rs.utils.hibernate.MySQL5Dialect (OK)
hibernate.connection.driver_class: com.mysql.jdbc.Driver (OK)
hibernate.connection.url: jdbc:mysql://localhost:3306/reportserver?useSSL=false&serverTimezone=UTC&allowPublicKeyRetrieval=true (OK)
hibernate.connection.username: root
hibernate.connection.password: ***********
hibernate.default_schema: reportserver

Connection Test: OK
Schema Version: RS3.0-15

19:16:29.057 INFO  n.datenwerke.rs.EnvironmentValidator - Validating database schema...
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.AdminResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.AdminResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.BasicRepositoryResource2
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.BasicRepositoryResource2
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.DataSourceResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.DataSourceResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.ExporterResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.ExporterResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.FilterRepositoryResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.FilterRepositoryResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.InfoResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.InfoResource
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.License
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.License
19:16:33.122 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.OlapDiscoverResource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.OlapDiscoverResource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.Query2Resource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.Query2Resource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.SessionResource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.SessionResource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.SaikuI18nResource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.SaikuI18nResource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.StatisticsResource
19:16:33.123 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.StatisticsResource
19:16:35.542 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile scheduler/scheduler.cf could not be loaded. Default values are in effect.
19:16:35.544 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile exportfilemd/pdfexport.cf could not be loaded. Default values are in effect.
19:16:35.545 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile exportfilemd/pdfexport.cf could not be loaded. Default values are in effect.
19:16:35.623 INFO  n.d.r.s.s.saiku.ThinQueryServiceImpl - loaded thinqueryservice
19:16:36.940 ERROR org.hibernate.AssertionFailure - HHH000099: an assertion failure occured (this may indicate a bug in Hibernate, but is more likely due to unsafe use of the session): org.hibernate.AssertionFailure: Table reportserver.RS_PARAMETER_DEFINITION not found
11-Mar-2020 19:16:36.942 SEVERE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal One or more Filters failed to start. Full details will be found in the appropriate container log file
11-Mar-2020 19:16:36.942 SEVERE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal Context [] startup failed due to previous errors
11-Mar-2020 19:16:36.963 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesJdbc The web application [ROOT] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
11-Mar-2020 19:16:36.965 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
com.mysql.jdbc.AbandonedConnectionCleanupThread.run(AbandonedConnectionCleanupThread.java:43)
11-Mar-2020 19:16:36.967 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kkjut813aja4t|25fc94c5]-AdminTaskTimer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.util.TimerThread.mainLoop(Timer.java:552)
java.util.TimerThread.run(Timer.java:505)
11-Mar-2020 19:16:36.967 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kkjut813aja4t|25fc94c5]-HelperThread-#0] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:16:36.967 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kkjut813aja4t|25fc94c5]-HelperThread-#1] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:16:36.968 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kkjut813aja4t|25fc94c5]-HelperThread-#2] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:16:36.968 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [com.google.inject.internal.util.$Finalizer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
com.google.inject.internal.util.$Finalizer.run(Finalizer.java:114)
11-Mar-2020 19:16:36.968 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Thread-9] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
com.google.inject.persist.jpa.JpaPersistService.begin(JpaPersistService.java:70)
com.google.inject.persist.jpa.JpaPersistService.get(JpaPersistService.java:50)
com.google.inject.persist.jpa.JpaPersistService.get(JpaPersistService.java:34)
com.google.inject.internal.BoundProviderFactory.get(BoundProviderFactory.java:55)
com.google.inject.internal.InjectorImpl$4$1.call(InjectorImpl.java:978)
com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1024)
com.google.inject.internal.InjectorImpl$4.get(InjectorImpl.java:974)
com.google.inject.internal.InjectorImpl.getInstance(InjectorImpl.java:1013)
net.datenwerke.rs.utils.eventlogger.jpa.JpaEventLoggerStartup$1.run(JpaEventLoggerStartup.java:32)
java.lang.Thread.run(Thread.java:748)
11-Mar-2020 19:16:36.968 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Thread-10] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Thread.sleep(Native Method)
net.datenwerke.gf.service.lateinit.LateInitStartup$1.run(LateInitStartup.java:41)
java.lang.Thread.run(Thread.java:748)
11-Mar-2020 19:16:36.969 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kkjut813aja4t|5d0f642a]-AdminTaskTimer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.util.TimerThread.mainLoop(Timer.java:552)
java.util.TimerThread.run(Timer.java:505)
11-Mar-2020 19:16:36.969 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kkjut813aja4t|5d0f642a]-HelperThread-#0] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:16:36.977 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kkjut813aja4t|5d0f642a]-HelperThread-#1] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:16:36.981 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kkjut813aja4t|5d0f642a]-HelperThread-#2] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:16:36.989 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/ROOT] has finished in [15,044] ms
11-Mar-2020 19:16:36.993 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
11-Mar-2020 19:16:37.004 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 15372 ms

Offline

#13 2020-03-11 23:18:34

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

How can I get this to work?

Offline

#14 2020-03-12 10:43:01

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jlux33,

could you please test with 3.1.0 ? We have a 3.1.0 installation with MySQL 5 and it definitely works, so if you have the same problem with 3.1.0 we will see that something is wrong with your installation.

Parallel to this we are installing 3.1.1 with MySQL 5 in order to check if there is something wrong with this version. We will let you know when this happens.

Regards,
Eduardo

Offline

#15 2020-03-12 22:44:02

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

Eduardo,

I am using the 3.1.1 version already.  I installed the RS3.1.1-6020-2020-01-28-16-18-55-reportserver-ce.zip self-install distribution.

This is not working.  Really don't know what to do next.

For completeness, this is my stack:
- Linux = CentOS Linux release 8.1.1911 (Core)
- Tomcat = 8.5
- MySQL = 5.7.29 (MySQL Community Server (GPL))
- Java = Java(TM) SE Runtime Environment (build 1.8.0_131-b11)

I would love to be able to use this platform.

Jim

Offline

#16 2020-03-13 08:29:26

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jim,

how are you using 3.1.1 as it is not starting in your case? I don't quite understand. If you mean the bitnami package: it uses an internal MariaDB database.

As the manual installation of 3.1.1 is not working in your case, I would recommend to try with manual installation of 3.1.0 to check if the problem doesn't has to do with your specific installation.

Regards,
Eduardo

Offline

#17 2020-03-13 15:28:39

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jim,

we tested ReportServer 3.1.1 together with MySQL 5.7.23 and this works fine. Something is wrong with your installation. Is your DB in the same host as your Tomcat? Do you have other applications in your Tomcat webapps?

Regards,
Eduardo

Offline

#18 2020-03-13 16:15:00

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

Eduardo,

Here are answers to your questions.

Is your DB in the same host as your Tomcat? - YES

Do you have other applications in your Tomcat webapps? - NO

Is there any logging I can look at?

Thank you for all your help.

Jim

Offline

#19 2020-03-14 17:16:03

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jim,

"how are you using 3.1.1 as it is not starting in your case? I don't quite understand. If you mean the bitnami package: it uses an internal MariaDB database."

Could you please check this?

Regards,
Eduardo

Offline

#20 2020-03-14 17:49:41

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

I am NOT usin* the bitnami installation.  I downloaded this file from the ReportServer.net downloads area.

RS3.1.1-6020-2020-01-28-16-18-55-reportserver-ce.zip

I then unzipped it in the tomcat ROOT folder as the install instruction state.

As said earlier, this is my stack:

- Linux = CentOS Linux release 8.1.1911 (Core)
- Tomcat = 8.5
- MySQL = 5.7.29 (MySQL Community Server (GPL))
- Java = Java(TM) SE Runtime Environment (build 1.8.0_131-b11)

Offline

#21 2020-03-26 20:19:41

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

I'm not sure what do do next here.  This just does not work and it makes no sense.  See above post for stack config and version info.  catalina.out below.

19:32:25.135 INFO  n.datenwerke.rs.EnvironmentValidator -




  _____                       _    _____                         
|  __ \                     | |  / ____|                         
| |__) |___ _ __   ___  _ __| |_| (___   ___ _ ____   _____ _ __
|  _  // _ \ '_ \ / _ \| '__| __|\___ \ / _ \ '__\ \ / / _ \ '__|
| | \ \  __/ |_) | (_) | |  | |_ ____) |  __/ |   \ V /  __/ |   
|_|  \_\___| .__/ \___/|_|   \__|_____/ \___|_|    \_/ \___|_|   
            | |                                                   
            |_|                                                   

Version: RS3.1.1-6020 2020-01-28-16-18-55
Code Version: 2020-01-28-16-14-09
Java Version: Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.131-b11 (1.8)
VM Args: -Djava.util.logging.config.file=/opt/tomcat/conf/logging.properties -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.security.egd=file:///dev/urandom -Djdk.tls.ephemeralDHKeySize=2048 -Djava.protocol.handler.pkgs=org.apache.catalina.webresources -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 -Xms512M -Xmx1024M -XX:+UseParallelGC -Dignore.endorsed.dirs= -Dcatalina.base=/opt/tomcat -Dcatalina.home=/opt/tomcat -Djava.io.tmpdir=/opt/tomcat/temp
rs.configdir: Not Configured

### DB Config ###
hibernate.dialect: net.datenwerke.rs.utils.hibernate.MySQL5Dialect (OK)
hibernate.connection.driver_class: com.mysql.jdbc.Driver (OK)
hibernate.connection.url: jdbc:mysql://localhost:3306/reportserver?useSSL=false&serverTimezone=UTC&allowPublicKeyRetrieval=true (OK)
hibernate.connection.username: root
hibernate.connection.password: ***********
hibernate.default_schema: reportserver

Connection Test: OK
Schema Version: RS3.0-15

19:32:25.141 INFO  n.datenwerke.rs.EnvironmentValidator - Validating database schema...
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.AdminResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.AdminResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.BasicRepositoryResource2
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.BasicRepositoryResource2
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.DataSourceResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.DataSourceResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.ExporterResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.ExporterResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.FilterRepositoryResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.FilterRepositoryResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.InfoResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.InfoResource
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.License
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.License
19:32:29.259 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.OlapDiscoverResource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.OlapDiscoverResource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.Query2Resource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.Query2Resource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.SessionResource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.SessionResource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.SaikuI18nResource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.SaikuI18nResource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.StatisticsResource
19:32:29.260 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.StatisticsResource
19:32:31.728 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile scheduler/scheduler.cf could not be loaded. Default values are in effect.
19:32:31.730 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile exportfilemd/pdfexport.cf could not be loaded. Default values are in effect.
19:32:31.731 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile exportfilemd/pdfexport.cf could not be loaded. Default values are in effect.
19:32:31.808 INFO  n.d.r.s.s.saiku.ThinQueryServiceImpl - loaded thinqueryservice
19:32:33.138 ERROR org.hibernate.AssertionFailure - HHH000099: an assertion failure occured (this may indicate a bug in Hibernate, but is more likely due to unsafe use of the session): org.hibernate.AssertionFailure: Table reportserver.RS_PARAMETER_DEFINITION not found
11-Mar-2020 19:32:33.140 SEVERE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal One or more Filters failed to start. Full details will be found in the appropriate container log file
11-Mar-2020 19:32:33.140 SEVERE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal Context [] startup failed due to previous errors
11-Mar-2020 19:32:33.160 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesJdbc The web application [ROOT] registered the JDBC driver [com.mysql.jdbc.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
11-Mar-2020 19:32:33.162 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
com.mysql.jdbc.AbandonedConnectionCleanupThread.run(AbandonedConnectionCleanupThread.java:43)
11-Mar-2020 19:32:33.163 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|25fc94c5]-AdminTaskTimer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.util.TimerThread.mainLoop(Timer.java:552)
java.util.TimerThread.run(Timer.java:505)
11-Mar-2020 19:32:33.164 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|25fc94c5]-HelperThread-#0] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:32:33.164 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|25fc94c5]-HelperThread-#1] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:32:33.165 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|25fc94c5]-HelperThread-#2] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:32:33.165 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [com.google.inject.internal.util.$Finalizer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
com.google.inject.internal.util.$Finalizer.run(Finalizer.java:114)
11-Mar-2020 19:32:33.165 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Thread-9] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
com.google.inject.persist.jpa.JpaPersistService.begin(JpaPersistService.java:70)
com.google.inject.persist.jpa.JpaPersistService.get(JpaPersistService.java:50)
com.google.inject.persist.jpa.JpaPersistService.get(JpaPersistService.java:34)
com.google.inject.internal.BoundProviderFactory.get(BoundProviderFactory.java:55)
com.google.inject.internal.InjectorImpl$4$1.call(InjectorImpl.java:978)
com.google.inject.internal.InjectorImpl.callInContext(InjectorImpl.java:1024)
com.google.inject.internal.InjectorImpl$4.get(InjectorImpl.java:974)
com.google.inject.internal.InjectorImpl.getInstance(InjectorImpl.java:1013)
net.datenwerke.rs.utils.eventlogger.jpa.JpaEventLoggerStartup$1.run(JpaEventLoggerStartup.java:32)
java.lang.Thread.run(Thread.java:748)
11-Mar-2020 19:32:33.165 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Thread-10] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Thread.sleep(Native Method)
net.datenwerke.gf.service.lateinit.LateInitStartup$1.run(LateInitStartup.java:41)
java.lang.Thread.run(Thread.java:748)
11-Mar-2020 19:32:33.165 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|5d0f642a]-AdminTaskTimer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.util.TimerThread.mainLoop(Timer.java:552)
java.util.TimerThread.run(Timer.java:505)
11-Mar-2020 19:32:33.166 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|5d0f642a]-HelperThread-#0] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:32:33.166 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|5d0f642a]-HelperThread-#1] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:32:33.166 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|5d0f642a]-HelperThread-#2] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:683)
11-Mar-2020 19:32:33.174 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/ROOT] has finished in [15,076] ms
11-Mar-2020 19:32:33.178 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
11-Mar-2020 19:32:33.194 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 15411 ms
11-Mar-2020 19:33:25.682 INFO [C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|25fc94c5]-AdminTaskTimer] org.apache.catalina.loader.WebappClassLoaderBase.checkStateForResourceLoading Illegal access: this web application instance has been stopped already. Could not load [com.mchange.v2.resourcepool.BasicResourcePool$AsyncTestIdleResourceTask]. The following stack trace is thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access.
    java.lang.IllegalStateException: Illegal access: this web application instance has been stopped already. Could not load [com.mchange.v2.resourcepool.BasicResourcePool$AsyncTestIdleResourceTask]. The following stack trace is thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access.
        at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForResourceLoading(WebappClassLoaderBase.java:1378)
        at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForClassLoading(WebappClassLoaderBase.java:1366)
        at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1218)
        at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1180)
        at com.mchange.v2.resourcepool.BasicResourcePool.checkIdleResources(BasicResourcePool.java:1629)
        at com.mchange.v2.resourcepool.BasicResourcePool.access$2000(BasicResourcePool.java:44)
        at com.mchange.v2.resourcepool.BasicResourcePool$CheckIdleResourcesTask.run(BasicResourcePool.java:2161)
        at java.util.TimerThread.mainLoop(Timer.java:555)
        at java.util.TimerThread.run(Timer.java:505)
Exception in thread "C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|25fc94c5]-AdminTaskTimer" java.lang.NoClassDefFoundError: com/mchange/v2/resourcepool/BasicResourcePool$AsyncTestIdleResourceTask
    at com.mchange.v2.resourcepool.BasicResourcePool.checkIdleResources(BasicResourcePool.java:1629)
    at com.mchange.v2.resourcepool.BasicResourcePool.access$2000(BasicResourcePool.java:44)
    at com.mchange.v2.resourcepool.BasicResourcePool$CheckIdleResourcesTask.run(BasicResourcePool.java:2161)
    at java.util.TimerThread.mainLoop(Timer.java:555)
    at java.util.TimerThread.run(Timer.java:505)
Caused by: java.lang.ClassNotFoundException: Illegal access: this web application instance has been stopped already. Could not load [com.mchange.v2.resourcepool.BasicResourcePool$AsyncTestIdleResourceTask]. The following stack trace is thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access.
    at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForClassLoading(WebappClassLoaderBase.java:1368)
    at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1218)
    at org.apache.catalina.loader.WebappClassLoaderBase.loadClass(WebappClassLoaderBase.java:1180)
    ... 5 more
Caused by: java.lang.IllegalStateException: Illegal access: this web application instance has been stopped already. Could not load [com.mchange.v2.resourcepool.BasicResourcePool$AsyncTestIdleResourceTask]. The following stack trace is thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access.
    at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForResourceLoading(WebappClassLoaderBase.java:1378)
    at org.apache.catalina.loader.WebappClassLoaderBase.checkStateForClassLoading(WebappClassLoaderBase.java:1366)
    ... 7 more
Exception in thread "C3P0PooledConnectionPoolManager[identityToken->z8kflta81kl4cj21kknfwu|5d0f642a]-AdminTaskTimer" java.lang.NoClassDefFoundError: com/mchange/v2/resourcepool/BasicResourcePool$AsyncTestIdleResourceTask
    at com.mchange.v2.resourcepool.BasicResourcePool.checkIdleResources(BasicResourcePool.java:1629)
    at com.mchange.v2.resourcepool.BasicResourcePool.access$2000(BasicResourcePool.java:44)
    at com.mchange.v2.resourcepool.BasicResourcePool$CheckIdleResourcesTask.run(BasicResourcePool.java:2161)
    at java.util.TimerThread.mainLoop(Timer.java:555)
    at java.util.TimerThread.run(Timer.java:505)

Offline

#22 2020-03-26 20:23:44

Jlux33
Member
Registered: 2020-03-08

Re: New Installation Startup Failing

When I login to mysql as root (same as persistence.properties file), I can access the table the log shows as "not found."

DLuxMac:~ jameslux$ ssh root@10.10.1.13
root@10.10.1.13's password:
Activate the web console with: systemctl enable --now cockpit.socket

Last login: Tue Mar 24 10:08:43 2020
[root@localhost ~]# mysql -u root -p
Enter password:
Welcome to the MySQL monitor.  Commands end with ; or \g.
Your MySQL connection id is 930290
Server version: 5.7.29 MySQL Community Server (GPL)

Copyright (c) 2000, 2020, Oracle and/or its affiliates. All rights reserved.

Oracle is a registered trademark of Oracle Corporation and/or its
affiliates. Other names may be trademarks of their respective
owners.

Type 'help;' or '\h' for help. Type '\c' to clear the current input statement.

mysql> select * from reportserver.RS_PARAMETER_DEFINITION;
Empty set (0.00 sec)

mysql>

Offline

#23 2020-03-27 09:03:48

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jlux33,

this is very strange, as I mentioned, this is working with a standard mysql 5 installation in our case.
I noticed some things:

1. VM Args: ... -Djava.security.egd=file:///dev/urandom -Djdk.tls.ephemeralDHKeySize=2048 -Djava.protocol.handler.pkgs=org.apache.catalina.webresources -Dorg.apache.catalina.security.SecurityListener.UMASK=0027

these are not standard VM arguments.

2. hibernate.connection.url: jdbc:mysql://...  useSSL=false&serverTimezone=UTC&allowPublicKeyRetrieval=true (OK)

these are also not standard jdbc arguments.

Can you please try without all these arguments, i..e. only with the default arguments / parameters of a mysql installation ?

Also, please try to connect to your mysql installation not with the mysql command, but with a jdbc client, putting the same jdbc url you use in reportserver. Maybe this  is something related to jdbc.

First of all, I would try with all default standard parameters / arguments, so your jdbc url should look like:
jdbc:mysql://localhost:3306/reportserver

and no VM arguments other than the standard. If this works, this has something to do with the extra options you have in your installation.

Regards,
Eduardo

Offline

#24 2020-04-12 02:38:18

CarlosR
Member
Registered: 2020-04-12

Re: New Installation Startup Failing

Hi everyone.

I had the same problem when trying to install reportserver with mysql 8 (on windows 10), I solved it by installing MariaDb (as the guide says) on another port 3360.

With this previous experience, I installed reportserver with Maria DB on MX-Linux (based on Debian 10) without any problem.

Here the error with reportserver and MySql:      <-----------------------------------------

### DB Config ###
hibernate.dialect: net.datenwerke.rs.utils.hibernate.MySQL5Dialect (OK)
hibernate.connection.driver_class: com.mysql.jdbc.Driver (OK)
hibernate.connection.url: jdbc:mysql://localhost:3306/reportserver (OK)
hibernate.connection.username: root
hibernate.connection.password: ********
hibernate.default_schema:

Connection Test: Failed (Could not create connection to database server.)

01:40:13.814 ERROR n.d.rs.ReportServerServiceConfig - ReportServer encountered an error while validating its environment. Startup interrupted.

2020-04-04 02:07:40 Apache Commons Daemon procrun stdout initialized.
02:08:13.984 INFO  n.datenwerke.rs.EnvironmentValidator -

Here the log with reportserver and Maria DB on port 3360   <-------------------------------------------

Version: RS3.1.2-6022 2020-04-01-15-17-05
Code Version: 2020-04-01-14-55-32
Java Version: Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.201-b09 (1.8)
VM Args: -Dcatalina.home=C:\Program Files\Apache Software Foundation\Tomcat 8.5 -Dcatalina.base=C:\Program Files\Apache Software Foundation\Tomcat 8.5 -Djava.io.tmpdir=C:\Program Files\Apache Software Foundation\Tomcat 8.5\temp -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.util.logging.config.file=C:\Program Files\Apache Software Foundation\Tomcat 8.5\conf\logging.properties -Drs.configdir=C:\Program Files\reportserver -Dfile.encoding=UTF8 exit abort -Xms512m -Xmx2048m
rs.configdir: C:\Program Files\reportserver (OK)

### DB Config ###
hibernate.dialect: net.datenwerke.rs.utils.hibernate.MariaDbDialect (OK)
hibernate.connection.driver_class: org.mariadb.jdbc.Driver (OK)
hibernate.connection.url: jdbc:mariadb://localhost:3360/reportserver (OK)
hibernate.connection.username: root
hibernate.connection.password: ********
hibernate.default_schema:

Connection Test: OK
Schema Version: RS3.0-16

Regards !!

Offline

#25 2020-04-21 09:18:51

eduardo
Administrator
Registered: 2016-11-01
Website

Re: New Installation Startup Failing

Hi Jlux33,

we had in a test installation a similar error: it was due to lower/uppercase in the table names.

We solved this by:

lower_case_table_names = 1
in /etc/mysql/mysql.conf.d/mysqld.cnf

Hope this helps.

Regards,
Eduardo

Offline

Board footer

Powered by FluxBB