14:37:47 localhost-startStop-1 INFO [standard] Module DefaultLogActivator started.Time used 62 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module ClassFactoryActivator starting... 14:37:47 localhost-startStop-1 INFO [standard] Module ClassFactoryActivator started.Time used 0 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module SerializationActivator starting... 14:37:47 localhost-startStop-1 INFO [standard] Module SerializationActivator started.Time used 2 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module JsonSerializationActivator starting... 14:37:47 localhost-startStop-1 INFO [standard] Module JsonSerializationActivator started.Time used 4 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module WorkspaceActivator starting... 14:37:47 localhost-startStop-1 INFO [standard] Module WorkspaceRegister starting... 14:37:47 localhost-startStop-1 INFO [standard] Module WorkspaceRegister started.Time used 0 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module WorkspaceActivator started.Time used 5 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module XMLableActivator starting... 14:37:47 localhost-startStop-1 INFO [standard] Module XMLableActivator started.Time used 0 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module BasicActivator started.Time used 649 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module ServerWorkspaceProvider starting... 14:37:47 localhost-startStop-1 INFO [standard] Module ServerWorkspaceProvider started.Time used 9 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module CoreActivator starting... 14:37:47 localhost-startStop-1 INFO [standard] Module BaseResourceActivator starting... 14:37:47 localhost-startStop-1 INFO [standard] Module BaseResourceActivator started.Time used 0 ms. 14:37:47 localhost-startStop-1 INFO [standard] Module BaseDBActivator starting... 14:37:47 pool-5-thread-1 ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] testOnBorrow is true, validationQuery not set 14:37:47 pool-5-thread-1 INFO [com.fr.third.alibaba.druid.pool.DruidDataSource] {dataSource-1} inited 14:37:49 localhost-startStop-1 INFO [standard] Module BaseDBActivator started.Time used 2099 ms. 14:37:49 localhost-startStop-1 INFO [standard] Module ConfigurationActivator starting... 14:37:49 localhost-startStop-1 INFO [standard] Module ConfigurationActivator started.Time used 228 ms. 14:37:49 localhost-startStop-1 INFO [standard] Module VirtualActivator starting... 14:37:49 localhost-startStop-1 INFO [standard] Module FineLoggerActivator starting... 14:37:49 localhost-startStop-1 ERROR [standard] GlobalMCastAddress is not matched: [224.0.1.60] from database, but [224.0.1.103] from this node! 14:37:49 localhost-startStop-1 ERROR [standard] LocalMCastAddress is not matched: [236.176.182.131] from database, but [228.94.164.243] from this node! 14:37:52 Thread-12 ERROR [standard] /opt/FineBI5.1/webapps/webroot/WEB-INF/assist/phantomjs can not found 14:37:52 CommonExecutor-1-thread-1 ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] testOnBorrow is true, validationQuery not set 14:37:53 localhost-startStop-1 ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] testOnBorrow is true, validationQuery not set 14:38:02 localhost-startStop-1 ERROR [standard] /opt/FineBI5.1/webapps/webroot/WEB-INF/assist/phantomjs can not found 14:40:02 ServiceExecuteRunnable0 ERROR [standard] java.io.FileNotFoundException: /opt/FineBI5.1/webapps/webroot/WEB-INF/../logs/cubes/bak/fine_real_time_usage/seg0/time/detail/0 (No such file or directory) at java.io.RandomAccessFile.open0(Native Method) at java.io.RandomAccessFile.open(RandomAccessFile.java:316) at java.io.RandomAccessFile.(RandomAccessFile.java:243) at java.io.RandomAccessFile.(RandomAccessFile.java:124) at com.fr.swift.io.nio.BaseAtomNio.loadBuffer(Unknown Source) at com.fr.swift.io.nio.BaseAtomNio.initBuf(Unknown Source) at com.fr.swift.io.nio.LongNio.put(Unknown Source) at com.fr.swift.segment.column.impl.base.LongDetailColumn.put(Unknown Source) at com.fr.swift.segment.column.impl.base.LongDetailColumn.put(Unknown Source) at com.fr.swift.segment.operator.insert.BaseInserter.putRow(Unknown Source) at com.fr.swift.segment.backup.FileSegmentBackup.backupRowData(Unknown Source) at com.fr.swift.segment.operator.insert.SwiftRealtimeInserter.putRow(Unknown Source) at com.fr.swift.segment.operator.insert.SwiftInserter.insertData(Unknown Source) at com.fr.swift.segment.operator.insert.SwiftRealtimeInserter.insertData(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.fr.swift.transaction.TransactionInvocationHandler.invoke(Unknown Source) at com.sun.proxy.$Proxy119.insertData(Unknown Source) at com.fr.swift.segment.operator.insert.BaseBlockInserter.insert(Unknown Source) at com.fr.swift.segment.operator.insert.BaseBlockInserter.insertData(Unknown Source) at com.fr.swift.service.SwiftRealtimeService$1.doJob(Unknown Source) at com.fr.swift.task.service.SwiftServiceCallable.call(Unknown Source) at com.fr.swift.task.service.ServiceExecuteRunnable.run(Unknown Source) at java.lang.Thread.run(Thread.java:748) 14:40:02 ServiceExecuteRunnable0 ERROR [standard] Invoke Inserter.insertData failed ! Do rollback! 14:40:02 ServiceExecuteRunnable0 ERROR [standard] java.io.FileNotFoundException: /opt/FineBI5.1/webapps/webroot/WEB-INF/../logs/cubes/bak/fine_real_time_usage/seg0/row_count/0 (No such file or directory) at java.io.RandomAccessFile.open0(Native Method) at java.io.RandomAccessFile.open(RandomAccessFile.java:316) at java.io.RandomAccessFile.(RandomAccessFile.java:243) at java.io.RandomAccessFile.(RandomAccessFile.java:124) at com.fr.swift.io.nio.BaseAtomNio.loadBuffer(Unknown Source) at com.fr.swift.io.nio.BaseAtomNio.initBuf(Unknown Source) at com.fr.swift.io.nio.IntNio.put(Unknown Source) at com.fr.swift.segment.BaseSegment.putRowCount(Unknown Source) at com.fr.swift.transaction.FileTransactionManager.rollback(Unknown Source) at com.fr.swift.transaction.TransactionInvocationHandler.rollback(Unknown Source) at com.fr.swift.transaction.TransactionInvocationHandler.invoke(Unknown Source) at com.sun.proxy.$Proxy119.insertData(Unknown Source) at com.fr.swift.segment.operator.insert.BaseBlockInserter.insert(Unknown Source) at com.fr.swift.segment.operator.insert.BaseBlockInserter.insertData(Unknown Source) at com.fr.swift.service.SwiftRealtimeService$1.doJob(Unknown Source) at com.fr.swift.task.service.SwiftServiceCallable.call(Unknown Source) at com.fr.swift.task.service.ServiceExecuteRunnable.run(Unknown Source) at java.lang.Thread.run(Thread.java:748) 14:40:02 ServiceExecuteRunnable0 ERROR [standard] java.sql.SQLException: java.lang.NullPointerException at com.fr.swift.service.SwiftRealtimeService$1.doJob(Unknown Source) at com.fr.swift.task.service.SwiftServiceCallable.call(Unknown Source) at com.fr.swift.task.service.ServiceExecuteRunnable.run(Unknown Source) at java.lang.Thread.run(Thread.java:748) Caused by: java.lang.NullPointerException at com.fr.swift.io.nio.IntNio.put(Unknown Source) at com.fr.swift.segment.BaseSegment.putRowCount(Unknown Source) at com.fr.swift.transaction.FileTransactionManager.rollback(Unknown Source) at com.fr.swift.transaction.TransactionInvocationHandler.rollback(Unknown Source) at com.fr.swift.transaction.TransactionInvocationHandler.invoke(Unknown Source) at com.sun.proxy.$Proxy119.insertData(Unknown Source) at com.fr.swift.segment.operator.insert.BaseBlockInserter.insert(Unknown Source) at com.fr.swift.segment.operator.insert.BaseBlockInserter.insertData(Unknown Source) ... 4 more 14:44:36 localhost-startStop-1 INFO [standard] Module DefaultLogActivator started.Time used 58 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module ClassFactoryActivator starting... 14:44:36 localhost-startStop-1 INFO [standard] Module ClassFactoryActivator started.Time used 0 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module SerializationActivator starting... 14:44:36 localhost-startStop-1 INFO [standard] Module SerializationActivator started.Time used 2 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module JsonSerializationActivator starting... 14:44:36 localhost-startStop-1 INFO [standard] Module JsonSerializationActivator started.Time used 2 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module WorkspaceActivator starting... 14:44:36 localhost-startStop-1 INFO [standard] Module WorkspaceRegister starting... 14:44:36 localhost-startStop-1 INFO [standard] Module WorkspaceRegister started.Time used 0 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module WorkspaceActivator started.Time used 5 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module XMLableActivator starting... 14:44:36 localhost-startStop-1 INFO [standard] Module XMLableActivator started.Time used 0 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module BasicActivator started.Time used 632 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module ServerWorkspaceProvider starting... 14:44:36 localhost-startStop-1 INFO [standard] Module ServerWorkspaceProvider started.Time used 9 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module CoreActivator starting... 14:44:36 localhost-startStop-1 INFO [standard] Module BaseResourceActivator starting... 14:44:36 localhost-startStop-1 INFO [standard] Module BaseResourceActivator started.Time used 0 ms. 14:44:36 localhost-startStop-1 INFO [standard] Module BaseDBActivator starting... 14:44:36 pool-5-thread-1 ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] testOnBorrow is true, validationQuery not set 14:44:36 pool-5-thread-1 INFO [com.fr.third.alibaba.druid.pool.DruidDataSource] {dataSource-1} inited 14:44:38 localhost-startStop-1 INFO [standard] Module BaseDBActivator started.Time used 2172 ms. 14:44:38 localhost-startStop-1 INFO [standard] Module ConfigurationActivator starting... 14:44:38 localhost-startStop-1 INFO [standard] Module ConfigurationActivator started.Time used 219 ms. 14:44:38 localhost-startStop-1 INFO [standard] Module VirtualActivator starting... 14:44:38 localhost-startStop-1 INFO [standard] Module FineLoggerActivator starting... 14:44:38 localhost-startStop-1 ERROR [standard] GlobalMCastAddress is not matched: [224.0.1.26] from database, but [224.0.1.103] from this node! 14:44:38 localhost-startStop-1 ERROR [standard] LocalMCastAddress is not matched: [231.152.34.75] from database, but [228.94.164.243] from this node! 14:44:47 CommonExecutor-1-thread-1 ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] testOnBorrow is true, validationQuery not set 14:44:52 localhost-startStop-1 ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] testOnBorrow is true, validationQuery not set 14:44:56 ForkJoinPool.commonPool-worker-2 ERROR [standard] java.lang.NullPointerException at com.fr.engine.bi.register.SchedulerRegister.printLog(SchedulerRegister.java:75) at com.fr.engine.bi.register.SchedulerRegister.initialize(SchedulerRegister.java:45) at com.fr.engine.bi.register.SchedulerRegister.initAllJob(SchedulerRegister.java:36) at java.util.concurrent.CompletableFuture$AsyncRun.run(CompletableFuture.java:1626) at java.util.concurrent.CompletableFuture$AsyncRun.exec(CompletableFuture.java:1618) at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:289) at java.util.concurrent.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1056) at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1692) at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157) 14:49:39 localhost-startStop-1 INFO [standard] Module DefaultLogActivator started.Time used 61 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module ClassFactoryActivator starting... 14:49:39 localhost-startStop-1 INFO [standard] Module ClassFactoryActivator started.Time used 0 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module SerializationActivator starting... 14:49:39 localhost-startStop-1 INFO [standard] Module SerializationActivator started.Time used 2 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module JsonSerializationActivator starting... 14:49:39 localhost-startStop-1 INFO [standard] Module JsonSerializationActivator started.Time used 2 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module WorkspaceActivator starting... 14:49:39 localhost-startStop-1 INFO [standard] Module WorkspaceRegister starting... 14:49:39 localhost-startStop-1 INFO [standard] Module WorkspaceRegister started.Time used 0 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module WorkspaceActivator started.Time used 5 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module XMLableActivator starting... 14:49:39 localhost-startStop-1 INFO [standard] Module XMLableActivator started.Time used 0 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module BasicActivator started.Time used 5689 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module ServerWorkspaceProvider starting... 14:49:39 localhost-startStop-1 INFO [standard] Module ServerWorkspaceProvider started.Time used 9 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module CoreActivator starting... 14:49:39 localhost-startStop-1 INFO [standard] Module BaseResourceActivator starting... 14:49:39 localhost-startStop-1 INFO [standard] Module BaseResourceActivator started.Time used 0 ms. 14:49:39 localhost-startStop-1 INFO [standard] Module BaseDBActivator starting... 14:49:40 pool-5-thread-1 ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] testOnBorrow is true, validationQuery not set 14:49:40 pool-5-thread-1 INFO [com.fr.third.alibaba.druid.pool.DruidDataSource] {dataSource-1} inited 14:49:50 pool-5-thread-1 WARN [com.fr.third.alibaba.druid.pool.DruidDataSource] not full timeout retry : 1 14:49:50 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:49:49 heartbeat - read: -2432 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:49:49 heartbeat - read: -2432 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:00 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:49:59 heartbeat - read: -2672 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:49:59 heartbeat - read: -2672 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:10 pool-5-thread-1 INFO [com.fr.third.alibaba.druid.pool.DruidDataSource] {dataSource-1} closed 14:50:10 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:10 heartbeat - read: -2899 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:10 heartbeat - read: -2899 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:10 pool-5-thread-1 ERROR [standard] Unable to open JDBC Connection for DDL execution com.fr.third.org.hibernate.exception.GenericJDBCException: Unable to open JDBC Connection for DDL execution at com.fr.third.org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:47) at com.fr.third.org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:111) at com.fr.third.org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:97) at com.fr.third.org.hibernate.resource.transaction.backend.jdbc.internal.DdlTransactionIsolatorNonJtaImpl.getIsolatedConnection(DdlTransactionIsolatorNonJtaImpl.java:69) at com.fr.third.org.hibernate.tool.schema.internal.exec.ImprovedExtractionContextImpl.getJdbcConnection(ImprovedExtractionContextImpl.java:60) at com.fr.third.org.hibernate.tool.schema.extract.internal.SequenceInformationExtractorLegacyImpl.extractMetadata(SequenceInformationExtractorLegacyImpl.java:40) at com.fr.third.org.hibernate.tool.schema.extract.internal.DatabaseInformationImpl.initializeSequences(DatabaseInformationImpl.java:65) at com.fr.third.org.hibernate.tool.schema.extract.internal.DatabaseInformationImpl.(DatabaseInformationImpl.java:59) at com.fr.third.org.hibernate.tool.schema.internal.Helper.buildDatabaseInformation(Helper.java:130) at com.fr.third.org.hibernate.tool.schema.internal.AbstractSchemaMigrator.doMigration(AbstractSchemaMigrator.java:92) at com.fr.third.org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.performDatabaseAction(SchemaManagementToolCoordinator.java:176) at com.fr.third.org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.process(SchemaManagementToolCoordinator.java:65) at com.fr.third.org.hibernate.internal.SessionFactoryImpl.(SessionFactoryImpl.java:476) at com.fr.third.org.hibernate.boot.internal.SessionFactoryBuilderImpl.build(SessionFactoryBuilderImpl.java:422) at com.fr.third.org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:710) at com.fr.third.org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:726) at com.fr.stable.db.session.SessionManager.createSessionFactory(Unknown Source) at com.fr.stable.db.session.SessionManager.createNewSessionFactory(Unknown Source) at com.fr.stable.db.DBContext.init(Unknown Source) at com.fr.config.activator.BaseDBActivator$1.call(Unknown Source) at com.fr.config.activator.BaseDBActivator$1.call(Unknown Source) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused by: java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:49:59 heartbeat - read: -2672 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:49:59 heartbeat - read: -2672 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:10 localhost-startStop-1 ERROR [standard] FineDB is not available! 14:50:10 localhost-startStop-1 ERROR [standard] Lifecycle fatal error : Please check the database service to ensure that it is available jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc 14:50:10 localhost-startStop-1 ERROR [standard] Lifecycle fatal error : Please check the database service to ensure that it is available jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc 14:50:10 localhost-startStop-1 ERROR [standard] Lifecycle fatal error : Please check the database service to ensure that it is available jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc 14:50:10 localhost-startStop-1 ERROR [standard] Lifecycle fatal error : Please check the database service to ensure that it is available jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc 14:50:10 localhost-startStop-1 ERROR [standard] Lifecycle fatal error : Please check the database service to ensure that it is available jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc 14:50:12 localhost-startStop-1 INFO [standard] Module ServerActivator starting... 14:50:12 localhost-startStop-1 INFO [standard] Module WebSocketActivator starting... 14:50:17 nioEventLoopGroup-2-1 INFO [standard] WebSocket server started at port:38888 14:50:17 localhost-startStop-1 INFO [standard] Module WebSocketActivator started.Time used 5349 ms. 14:50:17 localhost-startStop-1 INFO [standard] Module DecisionActivator starting... 14:50:17 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:17 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:17 localhost-startStop-1 INFO [standard] register cache: com.fr.decision.webservice.v10.entry.cache.EntryTreeCache 14:50:17 localhost-startStop-1 INFO [standard] register cache: com.fr.decision.webservice.v10.entry.cache.EntryTreeNodeCache 14:50:17 localhost-startStop-1 INFO [standard] register cache: com.fr.decision.webservice.v10.entry.cache.HomePageCache 14:50:17 localhost-startStop-1 INFO [standard] register cache: com.fr.decision.webservice.v10.entry.cache.HomePageNodeCache 14:50:17 localhost-startStop-1 INFO [standard] Module DecisionServletInitializer starting... 14:50:17 localhost-startStop-1 ERROR [standard] Lifecycle error.null java.lang.NullPointerException at com.fr.decision.base.DecisionServletInitializer.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:17 localhost-startStop-1 INFO [standard] Module DecisionServletInitializer started.Time used 0 ms. 14:50:17 localhost-startStop-1 INFO [standard] Module DecisionDBActivator starting... 14:50:17 localhost-startStop-1 INFO [standard] Persistence strategy NONE is set for cache, below properties will not be applied: diskStore, maxBytesLocalDisk 14:50:17 localhost-startStop-1 INFO [standard] cache invalidate com.fr.cluster.engine.base.FineClusterConfig 14:50:17 localhost-startStop-1 INFO [standard] cache invalidate com.fr.cluster.engine.base.FineClusterConfig 14:50:17 localhost-startStop-1 INFO [standard] cache invalidate com.fr.cluster.engine.base.FineClusterConfig 14:50:17 localhost-startStop-1 INFO [standard] cache invalidate com.fr.cluster.engine.base.FineClusterConfig 14:50:17 localhost-startStop-1 INFO [standard] cache invalidate com.fr.cluster.engine.base.FineClusterConfig 14:50:17 localhost-startStop-1 ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] testOnBorrow is true, validationQuery not set 14:50:17 localhost-startStop-1 INFO [com.fr.third.alibaba.druid.pool.DruidDataSource] {dataSource-2} inited 14:50:20 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:19 heartbeat - read: -2823 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:19 heartbeat - read: -2823 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:27 localhost-startStop-1 WARN [com.fr.third.alibaba.druid.pool.DruidDataSource] not full timeout retry : 1 14:50:31 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:30 heartbeat - read: -3059 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:30 heartbeat - read: -3059 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:41 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:40 heartbeat - read: -3279 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:40 heartbeat - read: -3279 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:42 localhost-startStop-1 INFO [standard] Using cache: com.fr.third.org.hibernate.cache.spi.UpdateTimestampsCache 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.third.org.hibernate.cache.internal.StandardQueryCache 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.report.entity.OffsetIPRelativeEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.finebi.entity.report.ReportIndexEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.AuthorityObjectEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.message.SystemMessageEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.HomePageExpandAuthorityObjectEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.BaseOutputActionEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.finebi.entity.authority.BiReportExpandAuthorityObjectEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.finebi.entity.authority.PackageRowFilterEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.workflow.bean.entity.WorkflowTaskEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.AuthorityEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.DepartmentEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.OutputPrintEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.OutputMountEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.ScheduleTaskEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.OutputClassEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.workflow.bean.entity.WorkflowTaskImplEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.OutputClientNotificationEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.workflow.bean.WorkflowStashData 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.message.MobileMessageEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.PostEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.mobile.entity.MobileDeviceEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.BackupNodeEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.workflow.bean.WorkflowNode 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.OutputFtpEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.OutputPlatformMSGEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.controller.provider.expander.entity.ProcessExpandAuthorityObjectEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.message.ProcessMessageEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.workflow.bean.WorkflowLog 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.report.entity.VcsEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.report.entity.RemoteDesignAuthorityEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.DepRoleEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.AuthPreferenceCfgEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.LoginDetailInfoEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.ScheduleRecordEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.report.entity.ParamsTemplateEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.FavoriteEntryEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.LoginLockEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.report.entity.PrintOffsetEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.CustomRoleEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.ScheduleOutputEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.workflow.bean.entity.WorkflowEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.ExtraPropertyEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.UserRoleMiddleEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.finebi.entity.report.ReportTablesIndexEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.report.write.entity.WriteStashEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.system.entity.message.MessageEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.OutputEmailEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.controller.provider.expander.entity.ReportExpandAuthorityObjectEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.schedule.base.entity.output.OutputSMSEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.security.entity.BlockIpEntity 14:50:43 localhost-startStop-1 INFO [standard] Using cache: com.fr.decision.authority.entity.UserEntity 14:50:51 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:50 heartbeat - read: -3494 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:50 heartbeat - read: -3494 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:53 localhost-startStop-1 INFO [com.fr.third.alibaba.druid.pool.DruidDataSource] {dataSource-2} closed 14:50:53 localhost-startStop-1 ERROR [standard] Unable to open JDBC Connection for DDL execution com.fr.third.org.hibernate.exception.GenericJDBCException: Unable to open JDBC Connection for DDL execution at com.fr.third.org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:47) at com.fr.third.org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:111) at com.fr.third.org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:97) at com.fr.third.org.hibernate.resource.transaction.backend.jdbc.internal.DdlTransactionIsolatorNonJtaImpl.getIsolatedConnection(DdlTransactionIsolatorNonJtaImpl.java:69) at com.fr.third.org.hibernate.tool.schema.internal.exec.ImprovedExtractionContextImpl.getJdbcConnection(ImprovedExtractionContextImpl.java:60) at com.fr.third.org.hibernate.tool.schema.extract.internal.SequenceInformationExtractorLegacyImpl.extractMetadata(SequenceInformationExtractorLegacyImpl.java:40) at com.fr.third.org.hibernate.tool.schema.extract.internal.DatabaseInformationImpl.initializeSequences(DatabaseInformationImpl.java:65) at com.fr.third.org.hibernate.tool.schema.extract.internal.DatabaseInformationImpl.(DatabaseInformationImpl.java:59) at com.fr.third.org.hibernate.tool.schema.internal.Helper.buildDatabaseInformation(Helper.java:130) at com.fr.third.org.hibernate.tool.schema.internal.AbstractSchemaMigrator.doMigration(AbstractSchemaMigrator.java:92) at com.fr.third.org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.performDatabaseAction(SchemaManagementToolCoordinator.java:176) at com.fr.third.org.hibernate.tool.schema.spi.SchemaManagementToolCoordinator.process(SchemaManagementToolCoordinator.java:65) at com.fr.third.org.hibernate.internal.SessionFactoryImpl.(SessionFactoryImpl.java:476) at com.fr.third.org.hibernate.boot.internal.SessionFactoryBuilderImpl.build(SessionFactoryBuilderImpl.java:422) at com.fr.third.org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:710) at com.fr.third.org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:726) at com.fr.stable.db.session.SessionManager.createSessionFactory(Unknown Source) at com.fr.stable.db.session.SessionManager.createNewSessionFactory(Unknown Source) at com.fr.stable.db.DBContext.init(Unknown Source) at com.fr.decision.db.DecisionDBActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused by: java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:50 heartbeat - read: -3494 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:50:50 heartbeat - read: -3494 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:50:53 localhost-startStop-1 INFO [standard] Module DecisionDBActivator started.Time used 35862 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module AuthorityActivator starting... 14:50:53 localhost-startStop-1 ERROR [standard] Database is not initialized java.lang.IllegalAccessException: Database is not initialized at com.fr.stable.db.DBContext.openSession(Unknown Source) at com.fr.stable.db.session.DAOSessionStore.openSession(Unknown Source) at com.fr.decision.authority.AuthorityContext.checkDefaultData(Unknown Source) at com.fr.decision.authority.AuthorityContext.init(Unknown Source) at com.fr.decision.authority.AuthorityActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] Module AuthorityActivator started.Time used 75 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module WebServiceActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] cache invalidate com.fr.decision.config.EncryptionConfig 14:50:53 localhost-startStop-1 INFO [standard] cpu monitor choose jdk monitor 14:50:53 localhost-startStop-1 INFO [standard] GarbageCollection monitor: Register PS Scavenge for [PS Eden Space, PS Survivor Space]. 14:50:53 localhost-startStop-1 INFO [standard] GarbageCollection monitor: Register PS MarkSweep for [PS Eden Space, PS Survivor Space, PS Old Gen]. 14:50:53 localhost-startStop-1 INFO [standard] Gc notification monitor choose GcNotificationMonitor 14:50:53 localhost-startStop-1 ERROR [standard] Database is not initialized java.lang.IllegalAccessException: Database is not initialized at com.fr.stable.db.DBContext.openSession(Unknown Source) at com.fr.stable.db.session.DAOSessionStore.openSession(Unknown Source) at com.fr.decision.base.db.transaction.DataOperatorMethodHooks.before(Unknown Source) at com.fr.decision.base.util.ProxyUtil$InvocationHandlerImpl.invoke(Unknown Source) at com.sun.proxy.$Proxy75.find(Unknown Source) at com.fr.decision.security.WebSecurityContext.fetchBlockIpsFromDB(Unknown Source) at com.fr.decision.security.WebSecurityContext.loadBlockIpList(Unknown Source) at com.fr.decision.system.SystemContext.init(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.initSystemContext(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.decision.webservice.v10.log.LogService.initLogJob(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.initServiceTimer(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.decision.webservice.v10.backup.module.BaseModuleBackup.(Unknown Source) at com.fr.decision.webservice.v10.backup.module.ConfigBackup.(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.registerBackupModule(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] cache invalidate com.fr.io.base.WebInfResourceFolders 14:50:53 localhost-startStop-1 INFO [standard] cache invalidate com.fr.decision.config.BackupConfig 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.decision.webservice.v10.backup.module.BaseModuleBackup.(Unknown Source) at com.fr.decision.webservice.v10.backup.module.JarBackup.(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.registerBackupModule(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] cache invalidate com.fr.decision.config.BackupConfig 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.decision.webservice.v10.backup.module.BaseModuleBackup.(Unknown Source) at com.fr.decision.webservice.v10.backup.module.JarBackupCluster.(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.registerBackupModule(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] cache invalidate com.fr.decision.config.BackupConfig 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.decision.webservice.v10.backup.module.BaseModuleBackup.(Unknown Source) at com.fr.decision.webservice.v10.backup.module.PluginsBackup.(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.registerBackupModule(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] cache invalidate com.fr.decision.config.BackupConfig 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.scheduler.ScheduleJobManager.checkJobIsExist(Unknown Source) at com.fr.decision.system.session.RemoveTimeoutSessionJob.start(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.registerTimeoutSession(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.scheduler.ScheduleJobManager.addJob(Unknown Source) at com.fr.decision.system.session.RemoveTimeoutSessionJob.start(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.registerTimeoutSession(Unknown Source) at com.fr.decision.webservice.WebServiceActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] Module URLAliasActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module URLAliasActivator started.Time used 1 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module RegisterServiceActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module RegisterServiceActivator started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module SystemMonitorActivator starting... 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 INFO [standard] Module SystemMonitorActivator started.Time used 11 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module SwiftLogSearchProviderActivator starting... 14:50:53 localhost-startStop-1 ERROR [standard] Lifecycle error.null java.lang.ExceptionInInitializerError at com.fr.log.impl.SwiftLogSearchProviderActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused by: java.lang.IllegalStateException: com.fr.swift.context.SwiftContext@73e11b1e has not been refreshed yet at com.fr.third.springframework.context.support.AbstractApplicationContext.assertBeanFactoryActive(AbstractApplicationContext.java:961) at com.fr.third.springframework.context.support.AbstractApplicationContext.getBean(AbstractApplicationContext.java:980) at com.fr.swift.adaptor.log.SwiftLogSearchProvider.(Unknown Source) at com.fr.swift.adaptor.log.SwiftLogSearchProvider.(Unknown Source) ... 29 more 14:50:53 localhost-startStop-1 INFO [standard] Module SwiftLogSearchProviderActivator started.Time used 7 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module BackupActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module BackupActivator started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module PluginServiceActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module PluginServiceActivator started.Time used 1 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module DecisionUpdateActivator starting... 14:50:53 localhost-startStop-1 ERROR [standard] update mobile key error 14:50:53 localhost-startStop-1 ERROR [standard] delete userType with no limit and no edit open error 14:50:53 localhost-startStop-1 INFO [standard] Module DecisionUpdateActivator started.Time used 61 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module WebServiceActivator started.Time used 211 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module GEOJSONActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module GEOJSONActivator started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module DecisionMessageActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module DecisionMessageActivator started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module ProfilerActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module ProfilerActivator started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module MessageRecorderActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module MessageRecorderActivator started.Time used 2 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module DecisionActivator started.Time used 36193 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module VirtualActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module WorkspaceServerActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module WorkspaceNettyServerActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module WorkspaceNettyServerActivator started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module WorkspaceServerActivator started.Time used 2 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module ServerWorkspaceRegister starting... 14:50:53 localhost-startStop-1 INFO [standard] Module ServerWorkspaceRegister started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module SocketLogAppenderActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module SocketLogAppenderActivator started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module ConfigSocketActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module ConfigSocketActivator started.Time used 0 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module VirtualActivator started.Time used 6 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module ScheduleActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module ScheduleActivator started.Time used 19 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module ReportActivator starting... 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 INFO [standard] Module WriteActivator starting... 14:50:53 localhost-startStop-1 INFO [standard] Module WriteActivator started.Time used 5 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module ReportAnalysisActivator starting... 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:53 localhost-startStop-1 INFO [standard] Module ReportAnalysisActivator started.Time used 4 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module ReportActivator started.Time used 80 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module DecisionActivator starting... 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.decision.webservice.v10.backup.module.BaseModuleBackup.(Unknown Source) at com.fr.decision.webservice.v10.backup.ReportletsBackup.(Unknown Source) at com.fr.decision.extension.report.DecisionActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] cache invalidate com.fr.decision.config.BackupConfig 14:50:53 localhost-startStop-1 INFO [standard] cache invalidate com.fr.decision.config.UpdatePushConfig 14:50:53 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.decision.webservice.v10.update.UpdatePushService.initPushJob(Unknown Source) at com.fr.decision.extension.report.DecisionActivator.initUpdatePush(Unknown Source) at com.fr.decision.extension.report.DecisionActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] Module WorkflowActivator starting... 14:50:53 localhost-startStop-1 ERROR [standard] Database is not initialized java.lang.IllegalAccessException: Database is not initialized at com.fr.stable.db.DBContext.openSession(Unknown Source) at com.fr.stable.db.session.DAOSessionStore.openSession(Unknown Source) at com.fr.decision.base.db.transaction.DataOperatorMethodHooks.before(Unknown Source) at com.fr.decision.base.util.ProxyUtil$InvocationHandlerImpl.invoke(Unknown Source) at com.sun.proxy.$Proxy90.find(Unknown Source) at com.fr.decision.workflow.util.WorkflowManager.initTask(Unknown Source) at com.fr.decision.workflow.util.WorkflowManager.init(Unknown Source) at com.fr.decision.workflow.util.WorkflowContext.init(Unknown Source) at com.fr.decision.workflow.util.WorkflowActivator.initWorkflowContext(Unknown Source) at com.fr.decision.workflow.util.WorkflowActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 ERROR [standard] Database is not initialized java.lang.IllegalAccessException: Database is not initialized at com.fr.stable.db.DBContext.openSession(Unknown Source) at com.fr.stable.db.session.DAOSessionStore.openSession(Unknown Source) at com.fr.decision.base.db.transaction.DataOperatorMethodHooks.before(Unknown Source) at com.fr.decision.base.util.ProxyUtil$InvocationHandlerImpl.invoke(Unknown Source) at com.sun.proxy.$Proxy92.find(Unknown Source) at com.fr.decision.workflow.util.WorkflowManager.initTaskImpl(Unknown Source) at com.fr.decision.workflow.util.WorkflowManager.init(Unknown Source) at com.fr.decision.workflow.util.WorkflowContext.init(Unknown Source) at com.fr.decision.workflow.util.WorkflowActivator.initWorkflowContext(Unknown Source) at com.fr.decision.workflow.util.WorkflowActivator.start(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:53 localhost-startStop-1 INFO [standard] Module WorkflowActivator started.Time used 32 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module DecisionActivator started.Time used 61 ms. 14:50:53 localhost-startStop-1 INFO [standard] Module ScheduleActivator starting... 14:50:54 localhost-startStop-1 INFO [standard] Module ScheduleActivator started.Time used 231 ms. 14:50:54 localhost-startStop-1 INFO [standard] Module CompatibleFSServiceActivator starting... 14:50:54 localhost-startStop-1 INFO [standard] Module CompatibleFSServiceActivator started.Time used 0 ms. 14:50:54 localhost-startStop-1 INFO [standard] Module VirtualActivator starting... 14:50:54 localhost-startStop-1 INFO [standard] Module FoundationActivator starting... 14:50:54 localhost-startStop-1 INFO [standard] --------->FoundationActivator start 14:50:54 localhost-startStop-1 INFO [standard] ------------------------start init engine obj --------- 14:50:54 localhost-startStop-1 INFO [standard] ==============direct.properties 14:50:54 localhost-startStop-1 INFO [standard] ----------input stream is null true 14:50:54 pool-8-thread-1 INFO [standard] Load cloud config spend time 640 ms 14:50:54 localhost-startStop-1 INFO [standard] ==============direct.properties 14:50:54 localhost-startStop-1 INFO [standard] ----------input stream is null true 14:50:54 localhost-startStop-1 INFO [standard] ------------------------end init engine obj --------- 14:50:55 localhost-startStop-1 INFO [standard] --------->start register js/css 14:50:55 localhost-startStop-1 INFO [standard] --------->end register js/css 14:50:55 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:55 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:55 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:55 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:55 localhost-startStop-1 WARN [standard] The ExportExecutor should be register first. 14:50:55 localhost-startStop-1 INFO [standard] --------->FoundationActivator activator end 14:50:55 localhost-startStop-1 INFO [standard] Module FoundationActivator started.Time used 1016 ms. 14:50:55 localhost-startStop-1 INFO [standard] Module DirectActivator starting... 14:50:55 localhost-startStop-1 INFO [standard] ============================== 14:50:55 localhost-startStop-1 INFO [standard] start: DirectActivator 14:50:55 localhost-startStop-1 INFO [standard] register Config success! cost 1 ms 14:50:55 Thread-11 INFO [standard] start registering com.fr.engine.sql.database.DirectDatabaseDataSourceDriver 14:50:55 Thread-12 INFO [standard] start registering com.fr.engine.server.driver.DirectServerDataSourceDriver 14:50:55 Thread-13 INFO [standard] start registering com.fr.engine.oldetl.driver.DirectOldETLDataSourceDriver 14:50:55 Thread-14 INFO [standard] start registering com.fr.engine.analysis.DirectAnalysisDataSourceDriver 14:50:55 Thread-15 INFO [standard] start registering com.fr.engine.distribute.local.source.DirectDistributedDataSourceDriver 14:50:55 Thread-17 INFO [standard] start registering com.fr.engine.sql.DirectSqlDataSourceDriver 14:50:55 Thread-16 INFO [standard] start registering com.fr.engine.circle.DirectCircleDataSourceDriver 14:50:55 Thread-18 INFO [standard] start registering com.fr.engine.analysis.conf.DirectConfAnalysisDataSourceDriver 14:50:55 Thread-19 INFO [standard] start registering com.fr.engine.excel.driver.DirectExcelDataSourceDriver 14:50:55 Thread-13 INFO [standard] end registering com.fr.engine.oldetl.driver.DirectOldETLDataSourceDriver 14:50:55 localhost-startStop-1 INFO [standard] register Model success! cost 433 ms 14:50:55 Thread-18 INFO [standard] end registering com.fr.engine.analysis.conf.DirectConfAnalysisDataSourceDriver 14:50:55 localhost-startStop-1 INFO [standard] register Connection success! cost 1 ms 14:50:55 Thread-17 INFO [standard] end registering com.fr.engine.sql.DirectSqlDataSourceDriver 14:50:55 Thread-11 INFO [standard] end registering com.fr.engine.sql.database.DirectDatabaseDataSourceDriver 14:50:55 Thread-12 INFO [standard] end registering com.fr.engine.server.driver.DirectServerDataSourceDriver 14:50:55 localhost-startStop-1 INFO [standard] register Scheduler success! cost 3 ms 14:50:55 Thread-16 INFO [standard] end registering com.fr.engine.circle.DirectCircleDataSourceDriver 14:50:55 localhost-startStop-1 INFO [standard] register DataMine success! cost 12 ms 14:50:55 Thread-19 INFO [standard] end registering com.fr.engine.excel.driver.DirectExcelDataSourceDriver 14:50:55 Thread-14 INFO [standard] end registering com.fr.engine.analysis.DirectAnalysisDataSourceDriver 14:50:55 ForkJoinPool.commonPool-worker-9 INFO [standard] Load chart map resources spend time 558 ms 14:50:55 ForkJoinPool.commonPool-worker-9 INFO [standard] ----------------- bi buildMapAssets content cost 561 14:50:55 localhost-startStop-1 INFO [standard] register CustomFunction success! cost 247 ms 14:50:55 localhost-startStop-1 INFO [standard] register TableChecker success! cost 2 ms 14:50:55 localhost-startStop-1 INFO [standard] register LicenseProxy success! cost 1 ms 14:50:55 localhost-startStop-1 INFO [standard] register NodeCounter success! cost 0 ms 14:50:55 localhost-startStop-1 INFO [standard] CacheEvictExpiredScheduler working 14:50:55 localhost-startStop-1 INFO [standard] register CacheEvictExpiredScheduler success! cost 2 ms 14:50:55 localhost-startStop-1 INFO [standard] register firstDayOfWeek success! cost 22 ms 14:50:55 localhost-startStop-1 INFO [standard] register ConfigCleaner success! cost 1 ms 14:50:55 localhost-startStop-1 INFO [standard] directAnalysisWithAllDirect is false 14:50:55 Thread-22 INFO [standard] cache invalidate com.finebi.common.internalimp.config.conf.relation.config.CommonDefaultRelation 14:50:55 Thread-22 INFO [standard] cache invalidate com.finebi.common.internalimp.config.conf.relation.config.CommonDefaultRelation 14:50:55 Thread-22 INFO [standard] cache invalidate com.finebi.common.internalimp.config.conf.relation.config.CommonCheckedRelation 14:50:55 Thread-22 INFO [standard] cache invalidate com.finebi.common.internalimp.config.conf.relation.config.CommonCheckedRelation 14:50:55 Thread-23 INFO [standard] com.fr.engine.bi.config.AnalysisExistsConfigChecker cost: 38ms 14:50:55 localhost-startStop-1 INFO [standard] register InterceptCheckRegister success! cost 53 ms 14:50:55 localhost-startStop-1 INFO [standard] cache invalidate com.finebi.common.internalimp.config.store.relation.conf.DashBoardDefaultRelation 14:50:55 localhost-startStop-1 INFO [standard] cache invalidate com.finebi.common.internalimp.config.store.relation.conf.DashBoardDefaultRelation 14:50:55 localhost-startStop-1 INFO [standard] cache invalidate com.finebi.common.internalimp.config.store.relation.conf.DashBoardCheckedRelation 14:50:55 localhost-startStop-1 INFO [standard] cache invalidate com.finebi.common.internalimp.config.store.relation.conf.DashBoardCheckedRelation 14:50:55 localhost-startStop-1 INFO [standard] register Config success! cost 10 ms 14:50:55 Thread-24 INFO [standard] start database tables sql init 14:50:55 Thread-24 INFO [standard] end database tables sql init 14:50:55 Thread-25 INFO [standard] com.fr.engine.distribute.local.activator.FieldIndexCacheActivator Async cost:2ms 14:50:55 localhost-startStop-1 ERROR [standard] com.fr.engine.distribute.local.activator.DSGenerateInfoFinishMapActivator start error! 14:50:55 localhost-startStop-1 INFO [standard] register SpiderModules success! cost 7 ms 14:50:55 localhost-startStop-1 INFO [standard] end: DirectActivator 14:50:56 localhost-startStop-1 INFO [standard] 提示代码：12400001 使用/sbin/ifconfig -a获取机器信息来验证是否注册 14:50:56 localhost-startStop-1 INFO [standard] ============================== vm time 101742 14:50:56 localhost-startStop-1 INFO [standard] Module DirectActivator started.Time used 1570 ms. 14:50:56 localhost-startStop-1 INFO [standard] Module DecisionDependencyActivator starting... 14:50:56 localhost-startStop-1 INFO [standard] Module DecisionDependencyActivator started.Time used 8 ms. 14:50:56 localhost-startStop-1 INFO [standard] Module BIUpdateActivator starting... 14:50:56 localhost-startStop-1 INFO [standard] *********update reportTablesIndex start********* 14:50:56 localhost-startStop-1 INFO [standard] *********update reportTablesIndex end********* 14:50:56 localhost-startStop-1 INFO [standard] cache invalidate com.finebi.conf.internalimp.config.ReportConfig 14:50:56 localhost-startStop-1 INFO [standard] cache invalidate com.finebi.conf.internalimp.config.ReportConfig 14:50:56 localhost-startStop-1 INFO [standard] Module BIUpdateActivator started.Time used 12 ms. 14:50:56 localhost-startStop-1 INFO [standard] Module VirtualActivator started.Time used 2607 ms. 14:50:56 localhost-startStop-1 INFO [standard] Module DecisionActivator starting... 14:50:56 localhost-startStop-1 ERROR [standard] java.lang.NullPointerException at com.fr.decision.webservice.v10.backup.module.BaseModuleBackup.(Unknown Source) at com.fr.decision.webservice.v10.backup.BiReportBackup.(BiReportBackup.java:18) at com.fr.decision.extension.finebi.DecisionActivator.start(DecisionActivator.java:145) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:56 localhost-startStop-1 INFO [standard] cache invalidate com.fr.decision.config.BackupConfig 14:50:56 localhost-startStop-1 ERROR [standard] update bi edit key error 14:50:56 localhost-startStop-1 ERROR [standard] delete userType with no limit and no edit open error 14:50:56 localhost-startStop-1 ERROR [standard] Database is not initialized java.lang.IllegalAccessException: Database is not initialized at com.fr.stable.db.DBContext.openSession(Unknown Source) at com.fr.stable.db.session.DAOSessionStore.openSession(Unknown Source) at com.fr.decision.base.db.transaction.DataOperatorMethodHooks.before(Unknown Source) at com.fr.decision.base.util.ProxyUtil$InvocationHandlerImpl.invoke(Unknown Source) at com.sun.proxy.$Proxy67.remove(Unknown Source) at com.fr.decision.extension.finebi.DecisionActivator.deleteBIMapNode(DecisionActivator.java:330) at com.fr.decision.extension.finebi.DecisionActivator.start(DecisionActivator.java:189) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.engine.FineModule.startChildren(Unknown Source) at com.fr.module.engine.FineModule.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.module.engine.FineModule.start(Unknown Source) at com.fr.module.ModuleRole.start(Unknown Source) at com.fr.startup.FineWebApplicationStartup.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer.onStartup(Unknown Source) at com.fr.third.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:175) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5204) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:50:56 localhost-startStop-1 INFO [standard] Module DecisionActivator started.Time used 46 ms. 14:50:56 localhost-startStop-1 INFO [standard] Module ScheduleActivator starting... 14:50:56 localhost-startStop-1 INFO [standard] Module ScheduleActivator started.Time used 0 ms. 14:50:56 localhost-startStop-1 INFO [standard] Module ServerActivator started.Time used 44598 ms. 14:50:56 localhost-startStop-1 ERROR [standard] Lifecycle error.Cannot initialize context because there is already a root application context present - check whether you have multiple ContextLoader* definitions in your web.xml! java.lang.IllegalStateException: Cannot initialize context because there is already a root application context present - check whether you have multiple ContextLoader* definitions in your web.xml! at com.fr.third.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:275) at com.fr.startup.FineWebApplicationStartup$SpringStarter.executeStart(Unknown Source) at com.fr.stable.lifecycle.AbstractLifecycle.start(Unknown Source) at com.fr.startup.FineWebApplicationInitializer$1.contextInitialized(Unknown Source) at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4751) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5215) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1421) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14:51:01 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:00 heartbeat - read: -3712 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:00 heartbeat - read: -3712 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:01 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:01 heartbeat - read: -4746 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:01 heartbeat - read: -4746 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:12 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:11 heartbeat - read: -3942 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:11 heartbeat - read: -3942 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:12 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:11 heartbeat - read: -3944 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:11 heartbeat - read: -3944 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:14 Thread-15 INFO [standard] Local spark cores: 16 14:51:14 Thread-15 INFO [standard] Using spark scheduler: ./temp/spider/fairscheduler.xml 14:51:17 Thread-15 INFO [standard] ActiveTaskManager reset! 14:51:17 Thread-15 INFO [standard] end registering com.fr.engine.distribute.local.source.DirectDistributedDataSourceDriver 14:51:17 SpaceCache thread-3 INFO [standard] create space info cost:90 ms 14:51:22 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:21 heartbeat - read: -4159 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:21 heartbeat - read: -4159 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:22 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:21 heartbeat - read: -4159 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:21 heartbeat - read: -4159 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:32 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:31 heartbeat - read: -4377 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:31 heartbeat - read: -4377 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:42 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:41 heartbeat - read: -4592 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:41 heartbeat - read: -4592 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:52 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:51 heartbeat - read: -4805 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:51:51 heartbeat - read: -4805 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:51:55 ScheduledThreadPool thread-1 INFO [standard] CacheEvictExpiredElementsJob finished 14:52:03 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:52:02 heartbeat - read: -5019 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:52:02 heartbeat - read: -5019 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:52:13 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:52:12 heartbeat - read: -5232 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:52:12 heartbeat - read: -5232 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:52:23 Druid-ConnectionPool-Create-2147300024jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:52:22 heartbeat - read: -5444 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:52:22 heartbeat - read: -5444 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more 14:52:33 Druid-ConnectionPool-Create-1184691573jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc ERROR [com.fr.third.alibaba.druid.pool.DruidDataSource] create connection error, url: jdbc:hsqldb:file:///opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db;hsqldb.tx=mvcc, errorCode -451, state S1000 java.sql.SQLException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:52:32 heartbeat - read: -5656 ms. at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:418) at com.fr.third.org.hsqldb.jdbc.JDBCUtil.sqlException(JDBCUtil.java:113) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3592) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.getConnection(JDBCDriver.java:313) at com.fr.third.org.hsqldb.jdbc.JDBCDriver.connect(JDBCDriver.java:261) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1461) at com.fr.third.alibaba.druid.pool.DruidAbstractDataSource.createPhysicalConnection(DruidAbstractDataSource.java:1525) at com.fr.third.alibaba.druid.pool.DruidDataSource$CreateConnectionThread.run(DruidDataSource.java:2153) Caused by: com.fr.third.org.hsqldb.HsqlException: Database lock acquisition failure: lockFile: com.fr.third.org.hsqldb.persist.LockFile@d3082908[file =/opt/FineBI5.1/webapps/webroot/WEB-INF/embed/finedb/db.lck, exists=true, locked=false, valid=false, ] method: checkHeartbeat read: 2019-11-19 06:52:32 heartbeat - read: -5656 ms. at com.fr.third.org.hsqldb.error.Error.error(Error.java:85) at com.fr.third.org.hsqldb.error.Error.error(Error.java:73) at com.fr.third.org.hsqldb.persist.LockFile.newLockFileLock(LockFile.java:642) at com.fr.third.org.hsqldb.persist.Logger.acquireLock(Logger.java:1415) at com.fr.third.org.hsqldb.persist.Logger.open(Logger.java:371) at com.fr.third.org.hsqldb.Database.reopen(Database.java:225) at com.fr.third.org.hsqldb.Database.open(Database.java:207) at com.fr.third.org.hsqldb.DatabaseManager.getDatabase(DatabaseManager.java:255) at com.fr.third.org.hsqldb.DatabaseManager.newSession(DatabaseManager.java:156) at com.fr.third.org.hsqldb.jdbc.JDBCConnection.(JDBCConnection.java:3570) ... 5 more