ReportServer Forum

gary.knapp

Member

Registered: 2016-05-12

Upgraded to RS3.0.3-6002 Runs but crashes

I upgraded a hosted server with  10.x.x.x and 172.x.x.x addresses.  The log shows three config files, not loading and an Error in the internal DB startup. The reportserver_dba user is set up with both IP addresses in MariaDB.

16:01:35.809 INFO  n.datenwerke.rs.EnvironmentValidator -

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

Version: RS3.0.3-6002 2017-12-09-12-26-19
Code Version: 2017-12-08-10-49-15
Java Version: Oracle Corporation OpenJDK 64-Bit Server VM 25.161-b14 (1.8)
VM Args: -Djava.util.logging.config.file=/data/opt/tomcat/conf/logging.properties -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Xms1024m -Xmx6144m -Drs.configdir=/var/lib/rsconfig -Djdk.tls.ephemeralDHKeySize=2048 -Djava.endorsed.dirs=/data/opt/tomcat/endorsed -Dcatalina.base=/data/opt/tomcat -Dcatalina.home=/data/opt/tomcat -Djava.io.tmpdir=/data/opt/tomcat/temp
rs.configdir: /var/lib/rsconfig (OK)

### 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://10.147.46.10:3306/reportserver (OK)
hibernate.connection.username: reportserver_dba
hibernate.connection.password: *********
hibernate.default_schema:

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

16:01:35.818 INFO  n.datenwerke.rs.EnvironmentValidator - Validating database schema...
16:01:55.898 INFO  n.datenwerke.rs.EnvironmentValidator - Schema validation completed
16:01:56.397 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.AdminResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.AdminResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.BasicRepositoryResource2
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.BasicRepositoryResource2
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.DataSourceResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.DataSourceResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.ExporterResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.ExporterResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.FilterRepositoryResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.FilterRepositoryResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.InfoResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.InfoResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.License
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.License
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.OlapDiscoverResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.OlapDiscoverResource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.Query2Resource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.Query2Resource
16:01:56.398 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.SessionResource
16:01:56.399 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.SessionResource
16:01:56.399 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.SaikuI18nResource
16:01:56.399 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.SaikuI18nResource
16:01:56.399 INFO  n.d.r.s.server.rest.SaikuRestModule - try to bind: net.datenwerke.rs.saiku.server.rest.resources.StatisticsResource
16:01:56.399 INFO  n.d.r.s.server.rest.SaikuRestModule - done binding: net.datenwerke.rs.saiku.server.rest.resources.StatisticsResource
16:01:59.272 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile scheduler/scheduler.cf could not be loaded. Default values are in effect.
16:01:59.274 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile exportfilemd/pdfexport.cf could not be loaded. Default values are in effect.
16:01:59.275 WARN  n.d.r.c.s.c.ConfigServiceImpl - Configfile exportfilemd/pdfexport.cf could not be loaded. Default values are in effect.
16:01:59.399 INFO  n.d.r.s.s.saiku.OlapUtilServiceImpl - loaded olap util service
16:01:59.401 INFO  n.d.r.s.s.saiku.ThinQueryServiceImpl - loaded thinqueryservice
16:02:25.195 INFO  n.d.r.c.s.i.RemoteMessageServiceImpl - Loading messages...
16:02:25.241 INFO  n.d.r.c.s.j.h.ReportServerExtenderHooker - Start loading ReportServer extensions.
16:02:30.603 ERROR n.d.r.c.s.i.InternalDbStartup - Error in Internal DB Startup
java.lang.RuntimeException: java.sql.SQLException: null,  message from server: "Host '172.24.16.134' is not allowed to connect to this MariaDB server"
    at net.datenwerke.dbpool.DbC3p0PoolServiceImpl$1.get(DbC3p0PoolServiceImpl.java:189) ~[reportserver.jar:na]
    at net.datenwerke.dbpool.DbC3p0PoolServiceImpl$1.get(DbC3p0PoolServiceImpl.java:1) ~[reportserver.jar:na]
    at net.datenwerke.rs.core.service.internaldb.TempTableServiceImpl.dropRSTMPtables(TempTableServiceImpl.java:70) ~[reportserver.jar:na]
    at net.datenwerke.rs.core.service.internaldb.InternalDbStartup$1.initialize(InternalDbStartup.java:45) ~[reportserver.jar:na]
    at net.datenwerke.gf.service.lateinit.LateInitStartup$1.run(LateInitStartup.java:62) [reportserver.jar:na]
    at java.lang.Thread.run(Thread.java:748) [na:1.8.0_161]
Caused by: java.sql.SQLException: null,  message from server: "Host '172.24.16.134' is not allowed to connect to this MariaDB server"
    at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:957) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:896) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:885) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.MysqlIO.doHandshake(MysqlIO.java:1038) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.ConnectionImpl.coreConnect(ConnectionImpl.java:2255) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.ConnectionImpl.connectOneTryOnly(ConnectionImpl.java:2286) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.ConnectionImpl.createNewIO(ConnectionImpl.java:2085) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.ConnectionImpl.<init>(ConnectionImpl.java:795) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.JDBC4Connection.<init>(JDBC4Connection.java:44) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at sun.reflect.GeneratedConstructorAccessor122.newInstance(Unknown Source) ~[na:na]
    at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) ~[na:1.8.0_161]
    at java.lang.reflect.Constructor.newInstance(Constructor.java:423) ~[na:1.8.0_161]
    at com.mysql.jdbc.Util.handleNewInstance(Util.java:404) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.ConnectionImpl.getInstance(ConnectionImpl.java:400) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mysql.jdbc.NonRegisteringDriver.connect(NonRegisteringDriver.java:327) ~[mysql-connector-java-5.1.38.jar:5.1.38]
    at com.mchange.v2.c3p0.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:175) ~[c3p0-0.9.5.jar:0.9.5]
    at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:220) ~[c3p0-0.9.5.jar:0.9.5]
    at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:211) ~[c3p0-0.9.5.jar:0.9.5]
    at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1127) ~[c3p0-0.9.5.jar:0.9.5]
    at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1114) ~[c3p0-0.9.5.jar:0.9.5]
    at com.mchange.v2.resourcepool.BasicResourcePool.access$800(BasicResourcePool.java:44) ~[c3p0-0.9.5.jar:0.9.5]
    at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1855) ~[c3p0-0.9.5.jar:0.9.5]
    at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696) ~[mchange-commons-java-0.2.9.jar:0.2.9]
16:02:31.278 INFO  n.d.r.c.s.i.RemoteMessageServiceImpl - Available locales: de, fi, ru, pt, bg, keys, lt, hr, lv, fr, hu, zh-CN, sk, sl, ga, sv, zh-TW, el, mt, en, it, es, et, cs, pl, da, ro, nl, tr
16:02:31.296 INFO  n.d.r.s.s.saiku.OlapUtilServiceImpl - loaded olap util service
16:03:04.035 INFO  n.d.r.s.s.search.SearchServiceImpl - Rebuilding search index...
16:03:08.455 INFO  n.d.g.s.lateinit.LateInitStartup - Startup completed

IF_Eduardo

Administrator

Registered: 2016-11-01

Website

Re: Upgraded to RS3.0.3-6002 Runs but crashes

Hi gary.knapp

this error comes from your mariaDB installation:
""Host '172.24.16.134' is not allowed to connect to this MariaDB server""

Please make sure that you can connect to the MariaDB server with the host specified.

Regards,
Eduardo

gary.knapp

Member

Registered: 2016-05-12

Re: Upgraded to RS3.0.3-6002 Runs but crashes

Thanks, I found the Datasource that was incorrectly configured.