H2 is an embeddable RDBMS written in Java.

H2 Database Engine

Last update: Jan 5, 2023

Related tags

Overview

Welcome to H2, the Java SQL database.

The main features of H2 are:

Very fast, open source, JDBC API
Embedded and server modes; disk-based or in-memory databases
Transaction support, multi-version concurrency
Browser based Console application
Encrypted databases
Fulltext search
Pure Java with small footprint: around 2.5 MB jar file size
ODBC driver

More information: https://h2database.com

Downloads

Download latest version or add to pom.xml:

<dependency>
    <groupId>com.h2database</groupId>
    <artifactId>h2</artifactId>
    <version>1.4.200</version>
</dependency>

Documentation

Support

Issue tracker for bug reports and feature requests
Mailing list / forum for questions about H2
'h2' tag on Stack Overflow for other questions (Hibernate with H2 etc.)

Comments

Size of database on disk keep growing without any reason

Hi,

I'm working on a project which use H2 database in production, but since few weeks, I've detected a problem about the size of the database.

Mainly, my application read the web and insert information in database every hour... very "simple". I've made some modification on my model lately, replacing old Auto-increment integer Id by UUID. It may be unrelated, but my system works now with UUID.

Main problem The problem is, with a standard size of 100 mb (podcast-server.mv.db after importing all the data), the database file keep growing and growing. In 2 days, it reaches 2.5 Gb ! And of course, the system is very slow when the database reaches a so important size.

I've run in the H2 console the following commands, the database stay so big :

DROP ALL OBJECTS;
RUNSCRIPT FROM '/shares/backup/bdd/podcas-server/podcast-server.sql'

If I totally remove the database and let the ORM (Hibernate) recreate it, and run exactly the same command, the database stays at ~100 Mb.

You can see the difference of size of the DB reseted 2016-JUN-02 and the same DB with two days of exploitation (2016-JUN-04) :

Experimentation 1 : To see if it's something related to H2, I've just try to DROP and RUNSCRIPT two times in a row in a fresh clean new database :

DROP ALL OBJECTS;
RUNSCRIPT FROM '/shares/backup/bdd/podcas-server/podcast-server.sql'
DROP ALL OBJECTS;
RUNSCRIPT FROM '/shares/backup/bdd/podcas-server/podcast-server.sql'

The result is a database with twice the size of what was expected.

After creating the database from hibernate : After one DROP ALL + RUNSCRIPT : After a second DROP ALL + RUNSCRIPT :

You can grab the sql file I use for the runscript operation here : https://drive.google.com/file/d/0B7UDg6z4yJZMVkhqbnhVSm5hdlU/view?usp=sharing

Experimentation 2 : To see if this could happen with any code, I've developed a very small example of Java app which create the DB, insert 100000 items in the DB (to reach the same quantity of information now in production) and see if I remove programmatically everything if the database file shrinks down. You can grab the code from this github repo

After one launch (so, with creation of db, no previous data...)

2016-06-04 12:30:13.124  INFO 21861 --- [           main] j.LocalContainerEntityManagerFactoryBean : Building JPA container EntityManagerFactory for persistence unit 'default'
2016-06-04 12:30:13.157  INFO 21861 --- [           main] o.hibernate.jpa.internal.util.LogHelper  : HHH000204: Processing PersistenceUnitInfo [
    name: default
    ...]
2016-06-04 12:30:13.469  INFO 21861 --- [           main] org.hibernate.Version                    : HHH000412: Hibernate Core {4.3.11.Final}
2016-06-04 12:30:13.471  INFO 21861 --- [           main] org.hibernate.cfg.Environment            : HHH000206: hibernate.properties not found
2016-06-04 12:30:13.472  INFO 21861 --- [           main] org.hibernate.cfg.Environment            : HHH000021: Bytecode provider name : javassist
2016-06-04 12:30:13.739  INFO 21861 --- [           main] o.hibernate.annotations.common.Version   : HCANN000001: Hibernate Commons Annotations {4.0.5.Final}
2016-06-04 12:30:14.225  INFO 21861 --- [           main] org.hibernate.dialect.Dialect            : HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2016-06-04 12:30:14.527  INFO 21861 --- [           main] o.h.h.i.ast.ASTQueryTranslatorFactory    : HHH000397: Using ASTQueryTranslatorFactory
2016-06-04 12:30:14.658  INFO 21861 --- [           main] org.hibernate.type.BasicTypeRegistry     : HHH000270: Type registration [java.util.Currency] overrides previous : org.hibernate.type.CurrencyType@2aa6311a
2016-06-04 12:30:15.041  INFO 21861 --- [           main] org.hibernate.tool.hbm2ddl.SchemaUpdate  : HHH000228: Running hbm2ddl schema update
2016-06-04 12:30:15.042  INFO 21861 --- [           main] org.hibernate.tool.hbm2ddl.SchemaUpdate  : HHH000102: Fetching database metadata
2016-06-04 12:30:15.044  INFO 21861 --- [           main] org.hibernate.tool.hbm2ddl.SchemaUpdate  : HHH000396: Updating schema
2016-06-04 12:30:15.058  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: cover
2016-06-04 12:30:15.060  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: item
2016-06-04 12:30:15.062  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: podcast
2016-06-04 12:30:15.064  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: podcast_tags
2016-06-04 12:30:15.067  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: tag
2016-06-04 12:30:15.069  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: watch_list
2016-06-04 12:30:15.071  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: watch_list_items
2016-06-04 12:30:15.073  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: cover
2016-06-04 12:30:15.074  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: item
2016-06-04 12:30:15.076  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: podcast
2016-06-04 12:30:15.078  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: podcast_tags
2016-06-04 12:30:15.080  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: tag
2016-06-04 12:30:15.082  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: watch_list
2016-06-04 12:30:15.084  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: watch_list_items
2016-06-04 12:30:15.086  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: cover
2016-06-04 12:30:15.087  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: item
2016-06-04 12:30:15.089  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: podcast
2016-06-04 12:30:15.092  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: podcast_tags
2016-06-04 12:30:15.093  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: tag
2016-06-04 12:30:15.095  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: watch_list
2016-06-04 12:30:15.096  INFO 21861 --- [           main] java.sql.DatabaseMetaData                : HHH000262: Table not found: watch_list_items
2016-06-04 12:30:15.165  INFO 21861 --- [           main] org.hibernate.tool.hbm2ddl.SchemaUpdate  : HHH000232: Schema update complete
2016-06-04 12:30:16.040  INFO 21861 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Registering beans for JMX exposure on startup
2016-06-04 12:30:16.295  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Before :: Number of Podcast 0
2016-06-04 12:30:16.304  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Before :: Number of Item 0
2016-06-04 12:30:16.310  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Before :: Number of Cover 0
2016-06-04 12:30:16.310  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Beginning of force insert
2016-06-04 12:30:16.310  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Insert of Podcast
2016-06-04 12:30:17.908  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Insert of Items
2016-06-04 12:30:53.760  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Inserted :: Number of Podcast 200
2016-06-04 12:30:57.956  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Inserted :: Number of Item 50000
2016-06-04 12:30:58.155  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Inserted :: Number of Cover 50200
2016-06-04 12:30:58.156  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Deletion
2016-06-04 12:31:31.604  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : Deletion done
2016-06-04 12:31:31.623  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : After :: Number of Podcast 0
2016-06-04 12:31:31.627  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : After :: Number of Item 0
2016-06-04 12:31:31.632  INFO 21861 --- [           main] d.IssueH2databaseApplication$BatchInsert : After :: Number of Cover 0
2016-06-04 12:31:31.634  INFO 21861 --- [           main] c.g.d.IssueH2databaseApplication         : Started IssueH2databaseApplication in 81.417 seconds (JVM running for 82.15)

And the size of the database state is :

If I relaunch the app, this times with the previous database, which is empty with a file size of 140 Mb :

2016-06-04 12:32:55.043  INFO 22211 --- [           main] j.LocalContainerEntityManagerFactoryBean : Building JPA container EntityManagerFactory for persistence unit 'default'
2016-06-04 12:32:55.082  INFO 22211 --- [           main] o.hibernate.jpa.internal.util.LogHelper  : HHH000204: Processing PersistenceUnitInfo [
    name: default
    ...]
2016-06-04 12:32:55.428  INFO 22211 --- [           main] org.hibernate.Version                    : HHH000412: Hibernate Core {4.3.11.Final}
2016-06-04 12:32:55.429  INFO 22211 --- [           main] org.hibernate.cfg.Environment            : HHH000206: hibernate.properties not found
2016-06-04 12:32:55.433  INFO 22211 --- [           main] org.hibernate.cfg.Environment            : HHH000021: Bytecode provider name : javassist
2016-06-04 12:32:55.675  INFO 22211 --- [           main] o.hibernate.annotations.common.Version   : HCANN000001: Hibernate Commons Annotations {4.0.5.Final}
2016-06-04 12:32:56.266  INFO 22211 --- [           main] org.hibernate.dialect.Dialect            : HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
2016-06-04 12:32:56.567  INFO 22211 --- [           main] o.h.h.i.ast.ASTQueryTranslatorFactory    : HHH000397: Using ASTQueryTranslatorFactory
2016-06-04 12:32:56.719  INFO 22211 --- [           main] org.hibernate.type.BasicTypeRegistry     : HHH000270: Type registration [java.util.Currency] overrides previous : org.hibernate.type.CurrencyType@2b59501e
2016-06-04 12:32:57.134  INFO 22211 --- [           main] org.hibernate.tool.hbm2ddl.SchemaUpdate  : HHH000228: Running hbm2ddl schema update
2016-06-04 12:32:57.135  INFO 22211 --- [           main] org.hibernate.tool.hbm2ddl.SchemaUpdate  : HHH000102: Fetching database metadata
2016-06-04 12:32:57.137  INFO 22211 --- [           main] org.hibernate.tool.hbm2ddl.SchemaUpdate  : HHH000396: Updating schema
2016-06-04 12:32:57.164  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000261: Table found: FORCE-INSERT.PUBLIC.COVER
2016-06-04 12:32:57.164  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000037: Columns: [width, id, url, height]
2016-06-04 12:32:57.164  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000108: Foreign keys: []
2016-06-04 12:32:57.164  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000126: Indexes: [primary_key_3]
2016-06-04 12:32:57.173  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000261: Table found: FORCE-INSERT.PUBLIC.ITEM
2016-06-04 12:32:57.174  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000037: Columns: [file_name, length, description, podcast_id, creation_date, cover_id, title, url, pub_date, mime_type, id, download_date, status]
2016-06-04 12:32:57.174  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000108: Foreign keys: [fk_juprtivkj6t8vuv3tqdrk0onu, fk_pmsdrydb6tt158xtq8cdigm0n]
2016-06-04 12:32:57.174  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000126: Indexes: [uk_ahjeq7cq2mi7yjg296g727qrg_index_2, primary_key_2, fk_juprtivkj6t8vuv3tqdrk0onu_index_2]
2016-06-04 12:32:57.181  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000261: Table found: FORCE-INSERT.PUBLIC.PODCAST
2016-06-04 12:32:57.181  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000037: Columns: [signature, last_update, description, id, cover_id, has_to_be_deleted, title, type, url]
2016-06-04 12:32:57.182  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000108: Foreign keys: [fk_alck7hbrtpw1g3k2jc9w216m0]
2016-06-04 12:32:57.182  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000126: Indexes: [primary_key_1, fk_alck7hbrtpw1g3k2jc9w216m0_index_1]
2016-06-04 12:32:57.195  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000261: Table found: FORCE-INSERT.PUBLIC.PODCAST_TAGS
2016-06-04 12:32:57.195  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000037: Columns: [podcasts_id, tags_id]
2016-06-04 12:32:57.195  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000108: Foreign keys: [fk_3o1sv9qvo2vcb0c5bme0dj9p3, fk_oj2a2jlpqrsc1acu4rtpovq8e]
2016-06-04 12:32:57.195  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000126: Indexes: [fk_3o1sv9qvo2vcb0c5bme0dj9p3_index_2, primary_key_27]
2016-06-04 12:32:57.201  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000261: Table found: FORCE-INSERT.PUBLIC.TAG
2016-06-04 12:32:57.201  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000037: Columns: [name, id]
2016-06-04 12:32:57.201  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000108: Foreign keys: []
2016-06-04 12:32:57.202  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000126: Indexes: [uk_1wdpsed5kna2y38hnbgrnhi5b_index_1, primary_key_14]
2016-06-04 12:32:57.207  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000261: Table found: FORCE-INSERT.PUBLIC.WATCH_LIST
2016-06-04 12:32:57.207  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000037: Columns: [name, id]
2016-06-04 12:32:57.208  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000108: Foreign keys: []
2016-06-04 12:32:57.208  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000126: Indexes: [primary_key_18]
2016-06-04 12:32:57.214  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000261: Table found: FORCE-INSERT.PUBLIC.WATCH_LIST_ITEMS
2016-06-04 12:32:57.214  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000037: Columns: [items_id, watch_lists_id]
2016-06-04 12:32:57.215  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000108: Foreign keys: [fk_d53piu62otqlr2sm3lpyh8rdf, fk_tkcosnnjil1df7ffd4ql6fmdc]
2016-06-04 12:32:57.215  INFO 22211 --- [           main] o.hibernate.tool.hbm2ddl.TableMetadata   : HHH000126: Indexes: [fk_tkcosnnjil1df7ffd4ql6fmdc_index_e, primary_key_e]
2016-06-04 12:32:57.224  INFO 22211 --- [           main] org.hibernate.tool.hbm2ddl.SchemaUpdate  : HHH000232: Schema update complete
2016-06-04 12:32:58.018  INFO 22211 --- [           main] o.s.j.e.a.AnnotationMBeanExporter        : Registering beans for JMX exposure on startup
2016-06-04 12:32:58.232  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Before :: Number of Podcast 0
2016-06-04 12:32:58.241  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Before :: Number of Item 0
2016-06-04 12:32:58.248  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Before :: Number of Cover 0
2016-06-04 12:32:58.248  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Beginning of force insert
2016-06-04 12:32:58.248  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Insert of Podcast
2016-06-04 12:32:59.899  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Insert of Items
2016-06-04 12:33:34.777  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Inserted :: Number of Podcast 200
2016-06-04 12:33:41.389  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Inserted :: Number of Item 50000
2016-06-04 12:33:41.702  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Inserted :: Number of Cover 50200
2016-06-04 12:33:41.703  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Deletion
2016-06-04 12:34:28.758  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : Deletion done
2016-06-04 12:34:28.784  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : After :: Number of Podcast 0
2016-06-04 12:34:28.789  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : After :: Number of Item 0
2016-06-04 12:34:28.794  INFO 22211 --- [           main] d.IssueH2databaseApplication$BatchInsert : After :: Number of Cover 0
2016-06-04 12:34:28.797  INFO 22211 --- [           main] c.g.d.IssueH2databaseApplication         : Started IssueH2databaseApplication in 96.925 seconds (JVM running for 97.867)

This times, the db took 7 Mb, so it weights 147 Mb :

I don't know what can I do to help you to investigate, but it seems very strange and I suspect a memory leak, which is very annoying...

Thanks for your help

opened by davinkevin 66

Large JSON and GEOMETRY objects

In the current H2 sizes of JSON and GEOMETRY objects are limited to 1,048,576 bytes. Sometimes it isn't enough (https://github.com/orbisgis/h2gis/issues/1179).

Hypothetically we can make this limit configurable, but from my point of view all really large objects must be off-heap on the server side (excluding possible usage in user-defined functions, we can't control them). It means they should be stored in the same way as LOBs.

There are two possible approaches. We can allocate a couple of new data types (JSON LARGE OBJECT and GEOMETRY LARGE OBJECT) or can add some storage specifiers (similar to FORMAT JSON) to existing CHARACTER LARGE OBJECT and BINARY LARGE OBJECT data types. I think new data types are less intrusive. We also can convert these data types to LOB data types, but with large in-place limit.

@grandinj @ebocher

opened by katzyn 54

h2 and hibernate-5.4.14 -> Column "min_value" not found

I use h2 with hibernate-5.4.14. When I use h2 version 1.4.200 I didn't get any exception. However, when I try to use today's master I get the following

18:20:11.916 [main] ERROR org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentImpl - Could not fetch the SequenceInformation from the database
org.h2.jdbc.JdbcSQLSyntaxErrorException: Column "min_value" not found [42122-200]
	at org.h2.message.DbException.getJdbcSQLException(DbException.java:459) ~[h2-1.4.201-SNAPSHOT.jar:?]
	at org.h2.message.DbException.getJdbcSQLException(DbException.java:435) ~[h2-1.4.201-SNAPSHOT.jar:?]
	at org.h2.message.DbException.get(DbException.java:200) ~[h2-1.4.201-SNAPSHOT.jar:?]
	at org.h2.message.DbException.get(DbException.java:176) ~[h2-1.4.201-SNAPSHOT.jar:?]
	at org.h2.jdbc.JdbcResultSet.getColumnIndex(JdbcResultSet.java:3252) ~[h2-1.4.201-SNAPSHOT.jar:?]
	at org.h2.jdbc.JdbcResultSet.get(JdbcResultSet.java:3340) ~[h2-1.4.201-SNAPSHOT.jar:?]
	at org.h2.jdbc.JdbcResultSet.getLong(JdbcResultSet.java:668) ~[h2-1.4.201-SNAPSHOT.jar:?]
	at org.hibernate.tool.schema.extract.internal.SequenceInformationExtractorLegacyImpl.resultSetMinValue(SequenceInformationExtractorLegacyImpl.java:134) ~[hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.tool.schema.extract.internal.SequenceInformationExtractorLegacyImpl.extractMetadata(SequenceInformationExtractorLegacyImpl.java:60) ~[hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentImpl.sequenceInformationList(JdbcEnvironmentImpl.java:403) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentImpl.<init>(JdbcEnvironmentImpl.java:268) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator.initiateService(JdbcEnvironmentInitiator.java:114) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator.initiateService(JdbcEnvironmentInitiator.java:35) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.boot.registry.internal.StandardServiceRegistryImpl.initiateService(StandardServiceRegistryImpl.java:101) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.service.internal.AbstractServiceRegistryImpl.createService(AbstractServiceRegistryImpl.java:263) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.service.internal.AbstractServiceRegistryImpl.initializeService(AbstractServiceRegistryImpl.java:237) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.service.internal.AbstractServiceRegistryImpl.getService(AbstractServiceRegistryImpl.java:214) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory.injectServices(DefaultIdentifierGeneratorFactory.java:152) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.service.internal.AbstractServiceRegistryImpl.injectDependencies(AbstractServiceRegistryImpl.java:286) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.service.internal.AbstractServiceRegistryImpl.initializeService(AbstractServiceRegistryImpl.java:243) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.service.internal.AbstractServiceRegistryImpl.getService(AbstractServiceRegistryImpl.java:214) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.boot.internal.InFlightMetadataCollectorImpl.<init>(InFlightMetadataCollectorImpl.java:176) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.boot.model.process.spi.MetadataBuildingProcess.complete(MetadataBuildingProcess.java:118) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.jpa.boot.internal.EntityManagerFactoryBuilderImpl.metadata(EntityManagerFactoryBuilderImpl.java:1214) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.jpa.boot.internal.EntityManagerFactoryBuilderImpl.build(EntityManagerFactoryBuilderImpl.java:1245) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at org.hibernate.jpa.HibernatePersistenceProvider.createEntityManagerFactory(HibernatePersistenceProvider.java:56) [hibernate-core-5.4.14-SNAPSHOT.jar:?]
	at javax.persistence.Persistence.createEntityManagerFactory(Persistence.java:79) [javax.persistence-api-2.2.jar:?]
	at javax.persistence.Persistence.createEntityManagerFactory(Persistence.java:54) [javax.persistence-api-2.2.jar:?]

Could anyone say where is the problem in h2 or in hibernate?

opened by PavelKastornyy 45

Add support for M and ZM dimensions to GEOMETRY data type
A complete parsing/formatting/storage support of XYM and XYZM dimensions in geometries is introduced. JTS geometries with 4 dimensions are also generated and accepted properly (JTS has a very limited support for it).

GEOMETRY data types does not depend on JTS library any more, all used features are reimplemented to support more dimension systems. JTS library can be used, however, in result sets and prepared statements to read or set values. Other ways are EWKT (String) and EWKB (byte[]) representations that can be also used to read or set values.

JTS geometries returned by H2 now always have at least 3 dimensions to resolve regression caused by a security fix, JTS is very inconsistent even with Z dimension.

This pull request is currently in a preview state.

(E)WKT / (E)WKB support was intentionally limited to features that are supported by JTS. OGC 06-103r4 and PostGis specifications have more types of geometries.

Personally I don't need anything above storage capabilities, there is no intention to provide more functionality.

@ebocher, @nicolas-f, @SPalominos Please take a look on it. This pull request should fix issue with 2D geometries that we discussed, but it may raise new incompatibilities.
opened by katzyn 41

Performance problem on commit

Hi there!

Performance problem on commit!

H2 Version:

h2-1.4.198.jar (soon to be replaced with 199)

Symptom:

H2 doing approx 3000 - 7000 read I/O's per second on "commit" This is periodically (especially when database gets bigger)
So a simple commit takes approx 1-2 minute before it continues to do anything else (typical H2 stack traces is below, typically: commit->tryCommit->freeUnusedChunks...)
After I do 'shutdown defrag' the issue is gone for a while... But after some hours the problem starts again...

Application Description (in short)

A server that receives Performance Counter Data from other systems (or performance collectors) The receiver is (parsing JSOn Data into Java classes) and put them in a QUEUE for storing in a H2 database
The thread "CentralPcsWriterHandler" just takes info from the above queue and stores the info in H2 (in various tables, in one schema per "performance-collector")
If the "persist queue size" is above 2, a warning message is written to the log

Extra info

Each "queue entry" is around 50-150 inserts (no clob/blob), mostly done as 1 transaction (to different tables)
Database size, for the moment is between 15G and 21G and constantly growing (15G before defrag and 21G after defrag) (shutdown defrag size details is also attached at the end)
OS: Red Hat Enterprise Linux Server release 7.5 (Maipo)
Java: openjdk version "1.8.0_181" OpenJDK Runtime Environment (build 1.8.0_181-b13) OpenJDK 64-Bit Server VM (build 25.181-b13, mixed mode)
H2 URL: jdbc:h2:file:/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB;DATABASE_TO_UPPER=false;MAX_COMPACT_TIME=2000;COMPRESS=TRUE;WRITE_DELAY=30000;DB_CLOSE_ON_EXIT=FALSE

Whats going on here...

Why are we doing humongous number of IO's on commit?
A commit should be "fast" (just flushing "dirty" (transaction log) pages to disk)
If we need to "cleanup" something... shouldn't that be done in some "housekeeper" thread, possibly with a lower priority

Any input on the above?

And thanks for your help and all your dedication :) /Goran

######################################################## Java Stack traces: ########################################################

"CentralPcsWriterHandler" #17 prio=5 os_prio=0 tid=0x00007f8970903800 nid=0x20be runnable [0x00007f8950166000]
   java.lang.Thread.State: RUNNABLE
        at sun.nio.ch.FileDispatcherImpl.pread0(Native Method)
        at sun.nio.ch.FileDispatcherImpl.pread(FileDispatcherImpl.java:52)
        at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:220)
        at sun.nio.ch.IOUtil.read(IOUtil.java:197)
        at sun.nio.ch.FileChannelImpl.readInternal(FileChannelImpl.java:741)
        at sun.nio.ch.FileChannelImpl.read(FileChannelImpl.java:727)
        at org.h2.store.fs.FileNio.read(FilePathNio.java:74)
        at org.h2.mvstore.DataUtils.readFully(DataUtils.java:406)
        at org.h2.mvstore.FileStore.readFully(FileStore.java:98)
        at org.h2.mvstore.MVStore.readBufferForPage(MVStore.java:1048)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1606)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1571)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1603)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1571)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1603)
        at org.h2.mvstore.MVStore.inspectVersion(MVStore.java:1513)
        at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1458)
        at org.h2.mvstore.MVStore.freeUnusedChunks(MVStore.java:1407)
        at org.h2.mvstore.MVStore.freeUnusedIfNeeded(MVStore.java:1400)
        at org.h2.mvstore.MVStore.storeNow(MVStore.java:1208)
        at org.h2.mvstore.MVStore.store(MVStore.java:1190)
        at org.h2.mvstore.MVStore.tryCommit(MVStore.java:1135)
        at org.h2.mvstore.tx.TransactionStore.endTransaction(TransactionStore.java:579)
        at org.h2.mvstore.tx.Transaction.commit(Transaction.java:396)
        at org.h2.engine.Session.commit(Session.java:693)
        at org.h2.command.dml.TransactionCommand.update(TransactionCommand.java:46)
        at org.h2.command.CommandContainer.update(CommandContainer.java:133)
        at org.h2.command.Command.executeUpdate(Command.java:267)
        - locked <0x0000000080d5a380> (a org.h2.engine.Session)
        at org.h2.jdbc.JdbcConnection.commit(JdbcConnection.java:497)
        - locked <0x00000000801541f0> (a org.h2.jdbc.JdbcConnection)
        at com.asetune.sql.conn.DbxConnection.commit(DbxConnection.java:1942)
        at com.asetune.central.pcs.CentralPersistWriterJdbc.saveSample(CentralPersistWriterJdbc.java:2154)
        at com.asetune.central.pcs.CentralPcsWriterHandler.consume(CentralPcsWriterHandler.java:696)
        at com.asetune.central.pcs.CentralPcsWriterHandler.run(CentralPcsWriterHandler.java:811)
        at java.lang.Thread.run(Thread.java:748)

"MVStore background writer nio:/sybdev/asetune/data/DBXTUNE_CENTRAL_DB.mv.db" #15 daemon prio=5 os_prio=0 tid=0x00007f8970842800 nid=0x20b2 in Object.wait() [0x00007f8950b7c000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        at org.h2.mvstore.MVStore$BackgroundWriterThread.run(MVStore.java:3116)
        - locked <0x0000000080789578> (a java.lang.Object)


########################################################
A couple of minutes later (next time it taking a long time)
########################################################

"CentralPcsWriterHandler" #17 prio=5 os_prio=0 tid=0x00007f8970903800 nid=0x20be waiting on condition [0x00007f8950167000]
   java.lang.Thread.State: WAITING (parking)
        at sun.misc.Unsafe.park(Native Method)
        - parking to wait for  <0x00000000e0abec30> (a java.util.concurrent.FutureTask)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at java.util.concurrent.FutureTask.awaitDone(FutureTask.java:429)
        at java.util.concurrent.FutureTask.get(FutureTask.java:191)
        at org.h2.mvstore.Page.readChildrenPositions(Page.java:296)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1607)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1571)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1603)
        at org.h2.mvstore.MVStore.inspectVersion(MVStore.java:1513)
        at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1458)
        at org.h2.mvstore.MVStore.freeUnusedChunks(MVStore.java:1407)
        at org.h2.mvstore.MVStore.freeUnusedIfNeeded(MVStore.java:1400)
        at org.h2.mvstore.MVStore.storeNow(MVStore.java:1208)
        at org.h2.mvstore.MVStore.store(MVStore.java:1190)
        at org.h2.mvstore.MVStore.tryCommit(MVStore.java:1135)
        at org.h2.mvstore.tx.TransactionStore.endTransaction(TransactionStore.java:579)
        at org.h2.mvstore.tx.Transaction.commit(Transaction.java:396)
        at org.h2.engine.Session.commit(Session.java:693)
        at org.h2.command.dml.TransactionCommand.update(TransactionCommand.java:46)
        at org.h2.command.CommandContainer.update(CommandContainer.java:133)
        at org.h2.command.Command.executeUpdate(Command.java:267)
        - locked <0x0000000080d5a380> (a org.h2.engine.Session)
        at org.h2.jdbc.JdbcConnection.commit(JdbcConnection.java:497)
        - locked <0x00000000801541f0> (a org.h2.jdbc.JdbcConnection)
        at com.asetune.sql.conn.DbxConnection.commit(DbxConnection.java:1942)
        at com.asetune.central.pcs.CentralPersistWriterJdbc.saveSample(CentralPersistWriterJdbc.java:2154)
        at com.asetune.central.pcs.CentralPcsWriterHandler.consume(CentralPcsWriterHandler.java:696)
        at com.asetune.central.pcs.CentralPcsWriterHandler.run(CentralPcsWriterHandler.java:811)
        at java.lang.Thread.run(Thread.java:748)

"MVStore background writer nio:/sybdev/asetune/data/DBXTUNE_CENTRAL_DB.mv.db" #15 daemon prio=5 os_prio=0 tid=0x00007f8970842800 nid=0x20b2 in Object.wait() [0x00007f8950b7c000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        at org.h2.mvstore.MVStore$BackgroundWriterThread.run(MVStore.java:3116)
        - locked <0x0000000080789578> (a java.lang.Object)
		

########################################################
Half an hour later...
########################################################

"CentralPcsWriterHandler" #17 prio=5 os_prio=0 tid=0x00007f8970903800 nid=0x20be runnable [0x00007f8950167000]
   java.lang.Thread.State: WAITING (parking)
        at sun.misc.Unsafe.park(Native Method)
        - parking to wait for  <0x00000000ea1adb18> (a java.util.concurrent.FutureTask)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at java.util.concurrent.FutureTask.awaitDone(FutureTask.java:429)
        at java.util.concurrent.FutureTask.get(FutureTask.java:191)
        at org.h2.mvstore.Page.readChildrenPositions(Page.java:296)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1607)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1571)
        at org.h2.mvstore.MVStore$ChunkIdsCollector.visit(MVStore.java:1603)
        at org.h2.mvstore.MVStore.inspectVersion(MVStore.java:1513)
        at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1458)
        at org.h2.mvstore.MVStore.freeUnusedChunks(MVStore.java:1407)
        at org.h2.mvstore.MVStore.freeUnusedIfNeeded(MVStore.java:1400)
        at org.h2.mvstore.MVStore.storeNow(MVStore.java:1208)
        at org.h2.mvstore.MVStore.store(MVStore.java:1190)
        at org.h2.mvstore.MVStore.tryCommit(MVStore.java:1135)
        at org.h2.mvstore.tx.TransactionStore.endTransaction(TransactionStore.java:579)
        at org.h2.mvstore.tx.Transaction.commit(Transaction.java:396)
        at org.h2.engine.Session.commit(Session.java:693)
        at org.h2.command.dml.TransactionCommand.update(TransactionCommand.java:46)
        at org.h2.command.CommandContainer.update(CommandContainer.java:133)
        at org.h2.command.Command.executeUpdate(Command.java:267)
        - locked <0x0000000080d5a380> (a org.h2.engine.Session)
        at org.h2.jdbc.JdbcConnection.commit(JdbcConnection.java:497)
        - locked <0x00000000801541f0> (a org.h2.jdbc.JdbcConnection)
        at com.asetune.sql.conn.DbxConnection.commit(DbxConnection.java:1942)
        at com.asetune.central.pcs.CentralPersistWriterJdbc.saveSample(CentralPersistWriterJdbc.java:2154)
        at com.asetune.central.pcs.CentralPcsWriterHandler.consume(CentralPcsWriterHandler.java:696)
        at com.asetune.central.pcs.CentralPcsWriterHandler.run(CentralPcsWriterHandler.java:811)
        at java.lang.Thread.run(Thread.java:748)

"MVStore background writer nio:/sybdev/asetune/data/DBXTUNE_CENTRAL_DB.mv.db" #15 daemon prio=5 os_prio=0 tid=0x00007f8970842800 nid=0x20b2 in Object.wait() [0x00007f8950b7c000]
   java.lang.Thread.State: TIMED_WAITING (on object monitor)
        at java.lang.Object.wait(Native Method)
        at org.h2.mvstore.MVStore$BackgroundWriterThread.run(MVStore.java:3116)
        - locked <0x0000000080789578> (a java.lang.Object)

######################################################## Linux iostat -xckt 5 ########################################################

03/19/2019 04:10:49 PM
avg-cpu:  %user   %nice %system %iowait  %steal   %idle
          16.69    0.00    8.97   40.33    0.00   34.01

Device:         rrqm/s   wrqm/s     r/s     w/s    rkB/s    wkB/s avgrq-sz avgqu-sz   await r_await w_await  svctm  %util
sda              16.40     0.00    4.40    1.60    83.20     8.00    30.40     0.00    0.37    0.50    0.00   0.20   0.12
sdb               0.00     0.00 5444.00    2.00 69795.20   948.00    25.98    15.72    2.89    2.88    5.40   0.15  83.72
dm-0              0.00     0.00    0.40    1.60     1.60     8.00     9.60     0.00    0.10    0.50    0.00   0.10   0.02
dm-1              0.00     0.00   20.40    0.00    81.60     0.00     8.00     0.01    0.49    0.49    0.00   0.05   0.10
dm-2              0.00     0.00 5451.40    2.00 70819.20   948.00    26.32    15.84    2.89    2.89    5.40   0.15  84.00

03/19/2019 04:10:54 PM
avg-cpu:  %user   %nice %system %iowait  %steal   %idle
          10.76    0.00   14.03   31.28    0.00   43.93

Device:         rrqm/s   wrqm/s     r/s     w/s    rkB/s    wkB/s avgrq-sz avgqu-sz   await r_await w_await  svctm  %util
sda               0.00     0.00    0.00    0.20     0.00     0.80     8.00     0.00    0.00    0.00    0.00   0.00   0.00
sdb               0.40     0.00 5518.80    0.00 176762.40     0.00    64.06     7.62    1.38    1.38    0.00   0.16  85.76
dm-0              0.00     0.00    0.00    0.20     0.00     0.80     8.00     0.00    0.00    0.00    0.00   0.00   0.00
dm-1              0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
dm-2              0.00     0.00 5513.00    0.00 175713.60     0.00    63.75     7.70    1.38    1.38    0.00   0.16  85.94

03/19/2019 04:10:59 PM
avg-cpu:  %user   %nice %system %iowait  %steal   %idle
          10.97    0.00   11.39   47.85    0.00   29.80

Device:         rrqm/s   wrqm/s     r/s     w/s    rkB/s    wkB/s avgrq-sz avgqu-sz   await r_await w_await  svctm  %util
sda               0.00     0.00    0.00    5.20     0.00    31.20    12.00     0.00    0.19    0.00    0.19   0.19   0.10
sdb               0.40     0.00 6266.80    4.40 97022.40  2159.20    31.63     8.32    1.33    1.33    9.91   0.13  82.74
dm-0              0.00     0.00    0.00    5.20     0.00    31.20    12.00     0.00    0.19    0.00    0.19   0.19   0.10
dm-1              0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
dm-2              0.00     0.00 6270.20    4.40 97022.40  2159.20    31.61     8.39    1.33    1.33    9.91   0.13  82.82

03/19/2019 04:11:04 PM
avg-cpu:  %user   %nice %system %iowait  %steal   %idle
           7.76    0.00   10.38   45.52    0.00   36.34

Device:         rrqm/s   wrqm/s     r/s     w/s    rkB/s    wkB/s avgrq-sz avgqu-sz   await r_await w_await  svctm  %util
sda               0.00     0.00    0.00    1.60     0.00     9.60    12.00     0.00    0.12    0.00    0.12   0.12   0.02
sdb               0.60     0.00 7000.60    0.40 89983.20     5.00    25.71    25.61    3.66    3.66    0.00   0.13  87.86
dm-0              0.00     0.00    0.00    1.60     0.00     9.60    12.00     0.00    0.12    0.00    0.12   0.12   0.02
dm-1              0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
dm-2              0.00     0.00 7003.00    0.40 90292.80     5.00    25.79    26.31    3.67    3.67    0.00   0.14  97.40

03/19/2019 04:11:09 PM
avg-cpu:  %user   %nice %system %iowait  %steal   %idle
          14.02    0.00    8.44   32.88    0.00   44.66

Device:         rrqm/s   wrqm/s     r/s     w/s    rkB/s    wkB/s avgrq-sz avgqu-sz   await r_await w_await  svctm  %util
sda               0.00     0.00    0.00    0.60     0.00     3.20    10.67     0.00    0.33    0.00    0.33   0.33   0.02
sdb               0.80     0.00 4247.00    0.00 96579.20     0.00    45.48    17.23    4.06    4.06    0.00   0.20  84.10
dm-0              0.00     0.00    0.00    0.60     0.00     3.20    10.67     0.00    0.33    0.00    0.33   0.33   0.02
dm-1              0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
dm-2              0.00     0.00 4249.60    0.00 96266.40     0.00    45.31    17.38    4.09    4.09    0.00   0.20  84.02

03/19/2019 04:11:14 PM
avg-cpu:  %user   %nice %system %iowait  %steal   %idle
           6.45    0.00   10.64   44.76    0.00   38.16

Device:         rrqm/s   wrqm/s     r/s     w/s    rkB/s    wkB/s avgrq-sz avgqu-sz   await r_await w_await  svctm  %util
sda               0.00     0.20    0.60    9.20     4.80    58.00    12.82     0.01    1.16    4.67    0.93   0.31   0.30
sdb               0.20     0.00 5724.60    0.20 76708.80    53.60    26.82    12.33    2.15    2.15    1.00   0.15  88.42
dm-0              0.00     0.00    0.60    9.40     4.80    58.00    12.56     0.01    1.16    4.67    0.94   0.30   0.30
dm-1              0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
dm-2              0.00     0.00 5727.40    0.20 77381.60    53.60    27.04    12.43    2.15    2.15    1.00   0.15  88.36

######################################################## Application error log, where you can see

Queue size (and the current time we have been working on CURRENT queue entry)
time it takes to persist/store a queue entry (normal time is 10-40 ms) but when we have the "commit issue" it takes 1-2 minutes ########################################################

2019-03-19 16:09:05,927 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 13 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:52.054
2019-03-19 16:09:14,747 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 14 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:00.874
2019-03-19 16:09:15,386 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 15 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:01.513
2019-03-19 16:09:23,027 - WARN  - qtp593687897-2643              - CentralPcsWriterHandler        - The persistent queue has 16 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:09.154
2019-03-19 16:09:24,987 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 17 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:11.114
2019-03-19 16:09:27,313 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 18 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:13.440
2019-03-19 16:09:31,168 - WARN  - qtp593687897-36                - CentralPcsWriterHandler        - The persistent queue has 19 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:17.295
2019-03-19 16:09:37,031 - WARN  - qtp593687897-2643              - CentralPcsWriterHandler        - The persistent queue has 20 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:23.158
2019-03-19 16:09:37,037 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 21 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:23.164
2019-03-19 16:09:41,321 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:08:13.026'. This persist took 87448 ms. jvmMemoryLeftInMB=1182. (00:01:27.448). inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,347 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:08:13.423'. This persist took 26 ms. jvmMemoryLeftInMB=1175. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,393 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:08:21.58'. This persist took 46 ms. jvmMemoryLeftInMB=1161. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,417 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:08:23.236'. This persist took 24 ms. jvmMemoryLeftInMB=1155. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,455 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:08:24.936'. This persist took 38 ms. jvmMemoryLeftInMB=1145. inserts=112, updates=26, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,460 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:08:30.589'. This persist took 5 ms. jvmMemoryLeftInMB=1145. inserts=52, updates=8, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,471 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_A1_ASE', sessionStartTime='2019-03-11 13:02:39.486', sessionSampleTime='2019-03-19 16:08:34.196'. This persist took 10 ms. jvmMemoryLeftInMB=1141. inserts=127, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,505 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_REP', sessionStartTime='2019-03-11 13:02:40.0', sessionSampleTime='2019-03-19 16:08:36.0'. This persist took 34 ms. jvmMemoryLeftInMB=1134. inserts=56, updates=16, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,514 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:08:43.373'. This persist took 8 ms. jvmMemoryLeftInMB=1129. inserts=118, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,546 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:08:43.836'. This persist took 31 ms. jvmMemoryLeftInMB=1122. inserts=114, updates=27, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,556 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:08:51.853'. This persist took 9 ms. jvmMemoryLeftInMB=1120. inserts=118, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,565 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:08:53.806'. This persist took 9 ms. jvmMemoryLeftInMB=1118. inserts=114, updates=27, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,610 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:08:55.426'. This persist took 45 ms. jvmMemoryLeftInMB=1108. inserts=124, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,614 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:09:00.781'. This persist took 4 ms. jvmMemoryLeftInMB=1106. inserts=53, updates=9, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,657 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_A1_ASE', sessionStartTime='2019-03-11 13:02:39.486', sessionSampleTime='2019-03-19 16:09:04.593'. This persist took 43 ms. jvmMemoryLeftInMB=1097. inserts=123, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,695 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:09:13.736'. This persist took 38 ms. jvmMemoryLeftInMB=1090. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,704 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:09:14.54'. This persist took 8 ms. jvmMemoryLeftInMB=1088. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,741 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:09:22.16'. This persist took 37 ms. jvmMemoryLeftInMB=1078. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,750 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:09:24.106'. This persist took 9 ms. jvmMemoryLeftInMB=1076. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,785 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:09:26.13'. This persist took 35 ms. jvmMemoryLeftInMB=1069. inserts=112, updates=26, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,789 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:09:30.969'. This persist took 4 ms. jvmMemoryLeftInMB=1067. inserts=52, updates=8, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,798 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_A1_ASE', sessionStartTime='2019-03-11 13:02:39.486', sessionSampleTime='2019-03-19 16:09:35.0'. This persist took 9 ms. jvmMemoryLeftInMB=1065. inserts=127, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:41,829 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_REP', sessionStartTime='2019-03-11 13:02:40.0', sessionSampleTime='2019-03-19 16:09:36.0'. This persist took 31 ms. jvmMemoryLeftInMB=1058. inserts=56, updates=16, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:45,587 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:09:44.24'. This persist took 8 ms. jvmMemoryLeftInMB=1054. inserts=118, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:45,757 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:09:44.94'. This persist took 35 ms. jvmMemoryLeftInMB=1042. inserts=114, updates=27, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:09:54,200 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:09:52.443'. This persist took 20 ms. jvmMemoryLeftInMB=1036. inserts=118, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:10:15,295 - WARN  - qtp593687897-2643              - CentralPcsWriterHandler        - The persistent queue has 3 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:20.010
2019-03-19 16:10:16,016 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 4 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:20.731
2019-03-19 16:10:25,986 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 5 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:30.701
2019-03-19 16:10:26,865 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 6 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:31.580
2019-03-19 16:10:27,971 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 7 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:32.686
2019-03-19 16:10:31,606 - WARN  - qtp593687897-36                - CentralPcsWriterHandler        - The persistent queue has 8 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:36.321
2019-03-19 16:10:37,412 - WARN  - qtp593687897-2643              - CentralPcsWriterHandler        - The persistent queue has 9 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:42.127
2019-03-19 16:10:37,658 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 10 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:42.373
2019-03-19 16:10:45,636 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 11 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:50.351
2019-03-19 16:10:46,865 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 12 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:51.580
2019-03-19 16:10:54,048 - WARN  - qtp593687897-36                - CentralPcsWriterHandler        - The persistent queue has 13 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:58.763
2019-03-19 16:10:56,608 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 14 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:01.323
2019-03-19 16:10:58,782 - WARN  - qtp593687897-36                - CentralPcsWriterHandler        - The persistent queue has 15 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:03.497
2019-03-19 16:11:02,122 - WARN  - qtp593687897-2643              - CentralPcsWriterHandler        - The persistent queue has 16 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:06.837
2019-03-19 16:11:07,952 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 17 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:12.667
2019-03-19 16:11:16,685 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 18 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:21.400
2019-03-19 16:11:18,671 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 19 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:23.386
2019-03-19 16:11:20,629 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:09:54.463'. This persist took 85344 ms. jvmMemoryLeftInMB=1125. (00:01:25.344). inserts=114, updates=27, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,653 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:09:56.606'. This persist took 23 ms. jvmMemoryLeftInMB=1115. inserts=124, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,663 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:10:01.147'. This persist took 9 ms. jvmMemoryLeftInMB=1111. inserts=53, updates=9, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,724 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_A1_ASE', sessionStartTime='2019-03-11 13:02:39.486', sessionSampleTime='2019-03-19 16:10:05.48'. This persist took 61 ms. jvmMemoryLeftInMB=1098. inserts=124, updates=30, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,766 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:10:14.596'. This persist took 41 ms. jvmMemoryLeftInMB=1088. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,780 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:10:15.273'. This persist took 14 ms. jvmMemoryLeftInMB=1085. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,815 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:10:24.79'. This persist took 35 ms. jvmMemoryLeftInMB=1075. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,824 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:10:22.97'. This persist took 9 ms. jvmMemoryLeftInMB=1075. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,857 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:10:27.133'. This persist took 33 ms. jvmMemoryLeftInMB=1065. inserts=112, updates=26, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,861 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:10:31.348'. This persist took 4 ms. jvmMemoryLeftInMB=1065. inserts=52, updates=8, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,868 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_REP', sessionStartTime='2019-03-11 13:02:40.0', sessionSampleTime='2019-03-19 16:10:37.0'. This persist took 7 ms. jvmMemoryLeftInMB=1061. inserts=56, updates=16, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,905 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_A1_ASE', sessionStartTime='2019-03-11 13:02:39.486', sessionSampleTime='2019-03-19 16:10:36.126'. This persist took 37 ms. jvmMemoryLeftInMB=1055. inserts=128, updates=30, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,913 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:10:44.866'. This persist took 8 ms. jvmMemoryLeftInMB=1051. inserts=118, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,946 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:10:45.643'. This persist took 33 ms. jvmMemoryLeftInMB=1045. inserts=114, updates=27, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,954 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:10:53.246'. This persist took 8 ms. jvmMemoryLeftInMB=1041. inserts=118, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,986 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:10:55.36'. This persist took 32 ms. jvmMemoryLeftInMB=1035. inserts=114, updates=27, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,995 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:10:57.633'. This persist took 9 ms. jvmMemoryLeftInMB=1031. inserts=124, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:20,998 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:11:01.584'. This persist took 3 ms. jvmMemoryLeftInMB=1031. inserts=53, updates=9, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:21,042 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_A1_ASE', sessionStartTime='2019-03-11 13:02:39.486', sessionSampleTime='2019-03-19 16:11:06.543'. This persist took 44 ms. jvmMemoryLeftInMB=1021. inserts=123, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:21,079 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:11:15.226'. This persist took 37 ms. jvmMemoryLeftInMB=1015. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:21,088 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:11:16.216'. This persist took 9 ms. jvmMemoryLeftInMB=1011. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:25,187 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:11:23.546'. This persist took 45 ms. jvmMemoryLeftInMB=1000. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:26,524 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:11:25.676'. This persist took 11 ms. jvmMemoryLeftInMB=994. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:29,204 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:11:28.35'. This persist took 36 ms. jvmMemoryLeftInMB=985. inserts=112, updates=26, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:32,010 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:11:31.783'. This persist took 4 ms. jvmMemoryLeftInMB=981. inserts=52, updates=8, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:38,312 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_REP', sessionStartTime='2019-03-11 13:02:40.0', sessionSampleTime='2019-03-19 16:11:37.0'. This persist took 5 ms. jvmMemoryLeftInMB=979. inserts=56, updates=16, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:11:58,897 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 3 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:20.299
2019-03-19 16:11:59,789 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 4 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:21.191
2019-03-19 16:12:02,308 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 5 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:23.710
2019-03-19 16:12:09,682 - WARN  - qtp593687897-36                - CentralPcsWriterHandler        - The persistent queue has 6 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:31.084
2019-03-19 16:12:16,896 - WARN  - qtp593687897-2643              - CentralPcsWriterHandler        - The persistent queue has 7 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:38.298
2019-03-19 16:12:17,650 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 8 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:39.052
2019-03-19 16:12:25,700 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 9 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:47.102
2019-03-19 16:12:28,514 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 10 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:49.916
2019-03-19 16:12:30,190 - WARN  - qtp593687897-36                - CentralPcsWriterHandler        - The persistent queue has 11 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:51.592
2019-03-19 16:12:33,166 - WARN  - qtp593687897-2643              - CentralPcsWriterHandler        - The persistent queue has 12 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:00:54.568
2019-03-19 16:12:38,645 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 13 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:00.047
2019-03-19 16:12:42,408 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 14 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:03.810
2019-03-19 16:12:47,267 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 15 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:08.669
2019-03-19 16:12:48,391 - WARN  - qtp593687897-36                - CentralPcsWriterHandler        - The persistent queue has 16 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:09.793
2019-03-19 16:12:55,751 - WARN  - qtp593687897-2643              - CentralPcsWriterHandler        - The persistent queue has 17 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:17.153
2019-03-19 16:12:58,059 - WARN  - qtp593687897-39                - CentralPcsWriterHandler        - The persistent queue has 18 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:19.461
2019-03-19 16:13:01,165 - WARN  - qtp593687897-2641              - CentralPcsWriterHandler        - The persistent queue has 19 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:22.567
2019-03-19 16:13:03,490 - WARN  - qtp593687897-35                - CentralPcsWriterHandler        - The persistent queue has 20 entries. The persistent writer might not keep in pace. The current consumer has been active for 00:01:24.892
2019-03-19 16:13:04,699 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_A1_ASE', sessionStartTime='2019-03-11 13:02:39.486', sessionSampleTime='2019-03-19 16:11:36.943'. This persist took 86101 ms. jvmMemoryLeftInMB=944. (00:01:26.101). inserts=127, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,720 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:11:45.816'. This persist took 20 ms. jvmMemoryLeftInMB=933. inserts=118, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,762 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:11:46.576'. This persist took 42 ms. jvmMemoryLeftInMB=923. inserts=114, updates=27, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,773 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:11:56.036'. This persist took 11 ms. jvmMemoryLeftInMB=920. inserts=114, updates=27, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,817 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:11:53.966'. This persist took 44 ms. jvmMemoryLeftInMB=910. inserts=118, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,846 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:11:58.84'. This persist took 29 ms. jvmMemoryLeftInMB=906. inserts=124, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,850 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:12:01.978'. This persist took 4 ms. jvmMemoryLeftInMB=903. inserts=53, updates=9, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,894 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_A1_ASE', sessionStartTime='2019-03-11 13:02:39.486', sessionSampleTime='2019-03-19 16:12:07.36'. This persist took 44 ms. jvmMemoryLeftInMB=896. inserts=123, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,930 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='INT_ASE', sessionStartTime='2019-03-11 13:03:06.273', sessionSampleTime='2019-03-19 16:12:16.156'. This persist took 36 ms. jvmMemoryLeftInMB=886. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,940 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='DEV_ASE', sessionStartTime='2019-03-11 13:02:55.653', sessionSampleTime='2019-03-19 16:12:16.883'. This persist took 10 ms. jvmMemoryLeftInMB=882. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,977 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='PROD_B1_ASE', sessionStartTime='2019-03-11 13:02:44.983', sessionSampleTime='2019-03-19 16:12:24.483'. This persist took 36 ms. jvmMemoryLeftInMB=875. inserts=122, updates=28, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:04,985 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='SYS_ASE', sessionStartTime='2019-03-11 13:02:59.873', sessionSampleTime='2019-03-19 16:12:26.486'. This persist took 8 ms. jvmMemoryLeftInMB=872. inserts=126, updates=29, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:05,020 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='STAGE_ASE', sessionStartTime='2019-03-11 13:03:13.283', sessionSampleTime='2019-03-19 16:12:29.44'. This persist took 34 ms. jvmMemoryLeftInMB=865. inserts=112, updates=26, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.
2019-03-19 16:13:05,024 - INFO  - CentralPcsWriterHandler        - CentralPcsWriterHandler        - Persisting Counters using 'CentralPersistWriterJdbc' for serverName='prod-psql1', sessionStartTime='2019-03-11 13:02:45.096', sessionSampleTime='2019-03-19 16:12:32.161'. This persist took 4 ms. jvmMemoryLeftInMB=865. inserts=55, updates=9, deletes=0, createTables=0, alterTables=0, dropTables=0, ddlSaveCount=0, ddlSaveCountSum=0, sqlCaptureBatchCount=0, sqlCaptureEntryCount=0, sqlCaptureBatchCountSum=0, sqlCaptureEntryCountSum=0.

######################################################## Application errlog of "shutdown defrag" logic ########################################################

2019-03-14 02:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-14 02:00:00,003 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-14 02:00:00,003 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-14 02:00:00,326 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=1846, sizeDiffPct=11.8%, doDefrag=false. LastSaved size is 15666 MB (15.3 GB) at '2019-03-13 22:16:35.599', Current size is 17512 MB (17.1 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-14 02:00:00,326 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-14 02:00:00,326 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-14 06:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-14 06:00:00,004 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-14 06:00:00,004 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-14 06:00:00,017 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=3786, sizeDiffPct=24.2%, doDefrag=false. LastSaved size is 15666 MB (15.3 GB) at '2019-03-13 22:16:35.599', Current size is 19452 MB (19.0 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-14 06:00:00,017 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-14 06:00:00,017 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-14 10:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-14 10:00:00,003 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-14 10:00:00,003 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-14 10:00:00,115 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=5737, sizeDiffPct=36.6%, doDefrag=true. LastSaved size is 15666 MB (15.3 GB) at '2019-03-13 22:16:35.599', Current size is 21403 MB (20.9 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-14 10:00:00,115 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-14 10:00:00,144 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-14 10:00:00,434 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-14 10:00:00,435 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-14 10:00:00,436 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-14 10:16:29,550 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 16:29.114 (MM:SS.ms)
2019-03-14 10:16:29,586 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-5695.5, BeforeMb=21403.3, AfterMb=15707.8, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-14 10:16:29,586 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=15707
2019-03-14 14:00:02,278 - INFO  - CentralH2Defrag                - 
2019-03-14 14:00:02,280 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-14 14:00:02,280 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-14 14:00:03,277 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=1943, sizeDiffPct=12.4%, doDefrag=false. LastSaved size is 15707 MB (15.3 GB) at '2019-03-14 10:16:29.586', Current size is 17650 MB (17.2 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-14 14:00:03,277 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-14 14:00:03,277 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-14 18:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-14 18:00:00,140 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-14 18:00:00,140 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-14 18:00:00,153 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=3915, sizeDiffPct=24.9%, doDefrag=false. LastSaved size is 15707 MB (15.3 GB) at '2019-03-14 10:16:29.586', Current size is 19622 MB (19.2 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-14 18:00:00,153 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-14 18:00:00,153 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-14 22:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-14 22:00:00,003 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-14 22:00:00,003 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-14 22:00:00,009 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=5878, sizeDiffPct=37.4%, doDefrag=true. LastSaved size is 15707 MB (15.3 GB) at '2019-03-14 10:16:29.586', Current size is 21585 MB (21.1 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-14 22:00:00,009 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-14 22:00:00,060 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-14 22:00:04,790 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-14 22:00:04,791 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-14 22:00:04,792 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-14 22:16:08,160 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 16:03.368 (MM:SS.ms)
2019-03-14 22:16:08,257 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-5835.7, BeforeMb=21585.6, AfterMb=15749.9, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-14 22:16:08,258 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=15749
2019-03-15 02:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-15 02:00:00,002 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-15 02:00:00,002 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-15 02:00:00,152 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=1976, sizeDiffPct=12.5%, doDefrag=false. LastSaved size is 15749 MB (15.4 GB) at '2019-03-14 22:16:08.257', Current size is 17725 MB (17.3 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-15 02:00:00,152 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-15 02:00:00,152 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-15 06:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-15 06:00:00,002 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-15 06:00:00,002 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-15 06:00:00,011 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4017, sizeDiffPct=25.5%, doDefrag=false. LastSaved size is 15749 MB (15.4 GB) at '2019-03-14 22:16:08.257', Current size is 19766 MB (19.3 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-15 06:00:00,011 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-15 06:00:00,011 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-15 10:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-15 10:00:00,002 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-15 10:00:00,002 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-15 10:00:00,081 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=6004, sizeDiffPct=38.1%, doDefrag=true. LastSaved size is 15749 MB (15.4 GB) at '2019-03-14 22:16:08.257', Current size is 21753 MB (21.2 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-15 10:00:00,082 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-15 10:00:00,132 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-15 10:00:10,374 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-15 10:00:10,375 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-15 10:01:54,825 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-15 10:21:35,441 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 21:25.066 (MM:SS.ms)
2019-03-15 10:21:35,468 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-5962.9, BeforeMb=21753.7, AfterMb=15790.8, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-15 10:21:35,468 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=15790
2019-03-15 14:00:00,092 - INFO  - CentralH2Defrag                - 
2019-03-15 14:00:00,093 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-15 14:00:00,093 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-15 14:00:00,277 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=1942, sizeDiffPct=12.3%, doDefrag=false. LastSaved size is 15790 MB (15.4 GB) at '2019-03-15 10:21:35.468', Current size is 17732 MB (17.3 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-15 14:00:00,277 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-15 14:00:00,277 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-15 18:00:00,193 - INFO  - CentralH2Defrag                - 
2019-03-15 18:00:00,195 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-15 18:00:00,195 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-15 18:00:00,452 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=3934, sizeDiffPct=24.9%, doDefrag=false. LastSaved size is 15790 MB (15.4 GB) at '2019-03-15 10:21:35.468', Current size is 19724 MB (19.3 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-15 18:00:00,453 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-15 18:00:00,453 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-15 22:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-15 22:00:00,002 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-15 22:00:00,003 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-15 22:00:00,197 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=5926, sizeDiffPct=37.5%, doDefrag=true. LastSaved size is 15790 MB (15.4 GB) at '2019-03-15 10:21:35.468', Current size is 21716 MB (21.2 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-15 22:00:00,198 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-15 22:00:01,543 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-15 22:00:06,102 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-15 22:00:06,103 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-15 22:00:06,107 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-15 22:16:17,334 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 16:11.229 (MM:SS.ms)
2019-03-15 22:16:17,365 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-5885.7, BeforeMb=21718.1, AfterMb=15832.4, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-15 22:16:17,367 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=15832
2019-03-16 02:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-16 02:00:00,003 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-16 02:00:00,003 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-16 02:00:00,132 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=2000, sizeDiffPct=12.6%, doDefrag=false. LastSaved size is 15832 MB (15.5 GB) at '2019-03-15 22:16:17.365', Current size is 17832 MB (17.4 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-16 02:00:00,132 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-16 02:00:00,132 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-16 06:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-16 06:00:00,002 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-16 06:00:00,002 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-16 06:00:00,008 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4001, sizeDiffPct=25.3%, doDefrag=false. LastSaved size is 15832 MB (15.5 GB) at '2019-03-15 22:16:17.365', Current size is 19833 MB (19.4 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-16 06:00:00,008 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-16 06:00:00,008 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-16 10:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-16 10:00:00,003 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-16 10:00:00,003 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-16 10:00:00,008 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=6013, sizeDiffPct=38.0%, doDefrag=true. LastSaved size is 15832 MB (15.5 GB) at '2019-03-15 22:16:17.365', Current size is 21845 MB (21.3 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-16 10:00:00,008 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-16 10:00:00,073 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-16 10:00:10,396 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-16 10:00:10,396 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-16 10:01:33,147 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-16 10:17:00,837 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 16:50.439 (MM:SS.ms)
2019-03-16 10:17:00,909 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-5971.8, BeforeMb=21845.1, AfterMb=15873.4, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-16 10:17:00,910 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=15873
2019-03-16 14:00:00,098 - INFO  - CentralH2Defrag                - 
2019-03-16 14:00:00,099 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-16 14:00:00,099 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-16 14:00:00,309 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=2044, sizeDiffPct=12.9%, doDefrag=false. LastSaved size is 15873 MB (15.5 GB) at '2019-03-16 10:17:00.909', Current size is 17917 MB (17.5 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-16 14:00:00,309 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-16 14:00:00,309 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-16 18:00:00,158 - INFO  - CentralH2Defrag                - 
2019-03-16 18:00:00,159 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-16 18:00:00,160 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-16 18:00:00,181 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4050, sizeDiffPct=25.5%, doDefrag=false. LastSaved size is 15873 MB (15.5 GB) at '2019-03-16 10:17:00.909', Current size is 19923 MB (19.5 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-16 18:00:00,181 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-16 18:00:00,182 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-16 22:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-16 22:00:00,001 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-16 22:00:00,002 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-16 22:00:00,170 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=6056, sizeDiffPct=38.2%, doDefrag=true. LastSaved size is 15873 MB (15.5 GB) at '2019-03-16 10:17:00.909', Current size is 21929 MB (21.4 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-16 22:00:00,170 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-16 22:00:00,282 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-16 22:00:03,196 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-16 22:00:03,197 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-16 22:00:03,202 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-16 22:16:05,776 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 16:02.578 (MM:SS.ms)
2019-03-16 22:16:05,803 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-6016.1, BeforeMb=21930.4, AfterMb=15914.2, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-16 22:16:05,803 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=15914
2019-03-17 02:00:00,003 - INFO  - CentralH2Defrag                - 
2019-03-17 02:00:00,023 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-17 02:00:00,023 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-17 02:00:00,441 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=2028, sizeDiffPct=12.7%, doDefrag=false. LastSaved size is 15914 MB (15.5 GB) at '2019-03-16 22:16:05.803', Current size is 17942 MB (17.5 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-17 02:00:00,441 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-17 02:00:00,441 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-17 06:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-17 06:00:00,002 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-17 06:00:00,002 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-17 06:00:00,008 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4141, sizeDiffPct=26.0%, doDefrag=true. LastSaved size is 15914 MB (15.5 GB) at '2019-03-16 22:16:05.803', Current size is 20055 MB (19.6 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-17 06:00:00,009 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-17 06:00:00,101 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-17 06:00:10,460 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-17 06:00:10,461 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-17 06:15:42,185 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-17 06:34:36,211 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 34:25.750 (MM:SS.ms)
2019-03-17 06:34:36,264 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-4114.0, BeforeMb=20055.2, AfterMb=15941.2, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-17 06:34:36,264 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=15941
2019-03-17 10:00:00,158 - INFO  - CentralH2Defrag                - 
2019-03-17 10:00:00,202 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-17 10:00:00,202 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-17 10:00:00,426 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=2025, sizeDiffPct=12.7%, doDefrag=false. LastSaved size is 15941 MB (15.6 GB) at '2019-03-17 06:34:36.264', Current size is 17966 MB (17.5 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-17 10:00:00,426 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-17 10:00:00,426 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-17 14:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-17 14:00:00,002 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-17 14:00:00,002 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-17 14:00:00,101 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4084, sizeDiffPct=25.6%, doDefrag=false. LastSaved size is 15941 MB (15.6 GB) at '2019-03-17 06:34:36.264', Current size is 20025 MB (19.6 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-17 14:00:00,101 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-17 14:00:00,101 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-17 18:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-17 18:00:00,002 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-17 18:00:00,003 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-17 18:00:00,044 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=6177, sizeDiffPct=38.7%, doDefrag=true. LastSaved size is 15941 MB (15.6 GB) at '2019-03-17 06:34:36.264', Current size is 22118 MB (21.6 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-17 18:00:00,044 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-17 18:00:00,059 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-17 18:00:00,245 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-17 18:00:00,246 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-17 18:00:00,250 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-17 18:15:00,204 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 14:59.957 (MM:SS.ms)
2019-03-17 18:15:00,233 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-6136.1, BeforeMb=22118.8, AfterMb=15982.7, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-17 18:15:00,234 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=15982
2019-03-17 22:00:00,114 - INFO  - CentralH2Defrag                - 
2019-03-17 22:00:00,114 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-17 22:00:00,115 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-17 22:00:00,254 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=2049, sizeDiffPct=12.8%, doDefrag=false. LastSaved size is 15982 MB (15.6 GB) at '2019-03-17 18:15:00.233', Current size is 18031 MB (17.6 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-17 22:00:00,254 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-17 22:00:00,254 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-18 02:00:00,001 - INFO  - CentralH2Defrag                - 
2019-03-18 02:00:00,003 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-18 02:00:00,003 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-18 02:00:00,010 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4148, sizeDiffPct=26.0%, doDefrag=true. LastSaved size is 15982 MB (15.6 GB) at '2019-03-17 18:15:00.233', Current size is 20130 MB (19.7 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-18 02:00:00,010 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-18 02:00:00,076 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-18 02:00:10,314 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-18 02:00:10,315 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-18 02:01:36,247 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-18 02:15:35,398 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 15:25.082 (MM:SS.ms)
2019-03-18 02:15:35,416 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-4120.2, BeforeMb=20130.6, AfterMb=16010.4, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-18 02:15:35,416 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=16010
2019-03-18 06:00:00,087 - INFO  - CentralH2Defrag                - 
2019-03-18 06:00:00,088 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-18 06:00:00,088 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-18 06:00:00,276 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=2110, sizeDiffPct=13.2%, doDefrag=false. LastSaved size is 16010 MB (15.6 GB) at '2019-03-18 02:15:35.416', Current size is 18120 MB (17.7 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-18 06:00:00,276 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-18 06:00:00,276 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-18 10:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-18 10:00:00,004 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-18 10:00:00,004 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-18 10:00:00,010 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4194, sizeDiffPct=26.2%, doDefrag=true. LastSaved size is 16010 MB (15.6 GB) at '2019-03-18 02:15:35.416', Current size is 20204 MB (19.7 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-18 10:00:00,010 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-18 10:00:00,012 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-18 10:00:10,270 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-18 10:00:10,271 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-18 10:00:27,420 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-18 10:17:36,543 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 17:26.272 (MM:SS.ms)
2019-03-18 10:17:36,599 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-4166.8, BeforeMb=20204.8, AfterMb=16037.9, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-18 10:17:36,599 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=16037
2019-03-18 14:00:00,465 - INFO  - CentralH2Defrag                - 
2019-03-18 14:00:00,479 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-18 14:00:00,479 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-18 14:00:00,848 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=2022, sizeDiffPct=12.6%, doDefrag=false. LastSaved size is 16037 MB (15.7 GB) at '2019-03-18 10:17:36.599', Current size is 18059 MB (17.6 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-18 14:00:00,848 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-18 14:00:00,848 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-18 18:00:00,003 - INFO  - CentralH2Defrag                - 
2019-03-18 18:00:00,004 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-18 18:00:00,004 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-18 18:00:00,013 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4117, sizeDiffPct=25.7%, doDefrag=true. LastSaved size is 16037 MB (15.7 GB) at '2019-03-18 10:17:36.599', Current size is 20154 MB (19.7 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-18 18:00:00,013 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-18 18:00:00,178 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-18 18:00:10,969 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-18 18:00:10,970 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-18 18:00:47,700 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-18 18:17:20,057 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 17:09.086 (MM:SS.ms)
2019-03-18 18:17:20,115 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-4089.4, BeforeMb=20154.9, AfterMb=16065.5, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-18 18:17:20,115 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=16065
2019-03-18 22:00:00,131 - INFO  - CentralH2Defrag                - 
2019-03-18 22:00:00,132 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-18 22:00:00,132 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-18 22:00:00,141 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=2060, sizeDiffPct=12.8%, doDefrag=false. LastSaved size is 16065 MB (15.7 GB) at '2019-03-18 18:17:20.115', Current size is 18125 MB (17.7 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-18 22:00:00,141 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-18 22:00:00,141 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-19 02:00:00,002 - INFO  - CentralH2Defrag                - 
2019-03-19 02:00:00,003 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-19 02:00:00,004 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-19 02:00:00,013 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=4080, sizeDiffPct=25.4%, doDefrag=false. LastSaved size is 16065 MB (15.7 GB) at '2019-03-18 18:17:20.115', Current size is 20145 MB (19.7 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-19 02:00:00,013 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-19 02:00:00,013 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-19 06:00:00,028 - INFO  - CentralH2Defrag                - 
2019-03-19 06:00:00,029 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-19 06:00:00,029 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-19 06:00:00,040 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=6015, sizeDiffPct=37.4%, doDefrag=true. LastSaved size is 16065 MB (15.7 GB) at '2019-03-18 18:17:20.115', Current size is 22080 MB (21.6 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-19 06:00:00,040 - INFO  - CentralH2Defrag                - Executing H2 Shutdown Defrag for CentralPersistWriterJdbc, which will also restart Dbx Central
2019-03-19 06:00:00,122 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-19 06:00:10,481 - INFO  - CentralPersistWriterJdbc       - Received a H2 database shutdown request with 'h2.shutdown.type' of 'DEFRAG'.
2019-03-19 06:00:10,482 - INFO  - CentralPersistWriterJdbc       - Checking for override of H2 shutdown type. Checking for file '/tmp/dbxcentral.shutdown.with.defrag'. exists=false
2019-03-19 06:17:31,778 - INFO  - CentralPersistWriterJdbc       - Sending SHUTDOWN DEFRAG to H2 database.
2019-03-19 06:38:25,310 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database using 'SHUTDOWN DEFRAG', took 38:14.827 (MM:SS.ms)
2019-03-19 06:38:25,328 - INFO  - CentralPersistWriterJdbc       - Shutdown H2 database file size info, after 'SHUTDOWN DEFRAG'. DiffMb=-5974.1, BeforeMb=22081.0, AfterMb=16106.9, Filename='/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'.
2019-03-19 06:38:25,372 - INFO  - CentralH2Defrag                - Saving H2 storage information to file '/tmp/dbxcentral.h2-storage-info.json'. dbFileSizeMb=16106
2019-03-19 10:00:00,375 - INFO  - CentralH2Defrag                - 
2019-03-19 10:00:00,376 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-19 10:00:00,376 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-19 10:00:00,878 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=1931, sizeDiffPct=12.0%, doDefrag=false. LastSaved size is 16106 MB (15.7 GB) at '2019-03-19 06:38:25.329', Current size is 18037 MB (17.6 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-19 10:00:00,878 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-19 10:00:00,879 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check
2019-03-19 14:00:00,150 - INFO  - CentralH2Defrag                - 
2019-03-19 14:00:00,152 - INFO  - CentralH2Defrag                - #############################################################################################
2019-03-19 14:00:00,152 - INFO  - CentralH2Defrag                - Begin task: H2 Shutdown Defrag check
2019-03-19 14:00:00,216 - INFO  - CentralH2Defrag                - H2 database storage sizeDiffMb=3923, sizeDiffPct=24.4%, doDefrag=false. LastSaved size is 16106 MB (15.7 GB) at '2019-03-19 06:38:25.329', Current size is 20029 MB (19.6 GB), for H2 database file '/home/sybase/.dbxtune/dbxc/data/DBXTUNE_CENTRAL_DB.mv.db'. Threshold in MB 4096, which can be changed with property 'CentralH2Defrag.threshold.mb'.
2019-03-19 14:00:00,216 - INFO  - CentralH2Defrag                - Skipping H2 Shutdown Defrag, doDefrag = false.
2019-03-19 14:00:00,216 - INFO  - CentralH2Defrag                - End task: H2 Shutdown Defrag check

opened by goranschwarz 40

Intermittent TestFileSystem failures

Here is a sample stack trace:

18:51:25 00:12.264 org.h2.test.unit.TestFileSystem Expected: -1792929026 actual: -369484912Exception in thread 
org.h2.test.unit.TestFileSystem$13:1867" java.lang.AssertionError: Expected: -1792929026 actual: -369484912
	at org.h2.test.TestBase.fail(TestBase.java:327)
	at org.h2.test.TestBase.assertEquals(TestBase.java:499)
	at org.h2.test.unit.TestFileSystem$13.call(TestFileSystem.java:788)
	at org.h2.util.Task.run(Task.java:44)
	at java.lang.Thread.run(Thread.java:748)```

It looks like FileEncrypt (and XTS object within it in particular) is not thread-safe, but I can't figure out why. Synchronizing XTS.encrypt() and XTS.decrypt() seems to fix the problem though.

MVStore

opened by andreitokar 39

Sporadic NullPointerException in LobStorageMap in 1.4.199

After upgrading H2 from 1.4.197 to 1.4.199 I started to see sporadic NullPointerExceptions in LobStorageMap:

Caused by: java.lang.NullPointerException
	at org.h2.store.LobStorageMap.copyLob(LobStorageMap.java:255)
	at org.h2.value.ValueLobDb.copyToResult(ValueLobDb.java:554)
	at org.h2.value.ValueLobDb.copyToResult(ValueLobDb.java:40)
	at org.h2.result.LocalResultImpl.cloneLobs(LocalResultImpl.java:280)
	at org.h2.result.LocalResultImpl.addRow(LocalResultImpl.java:315)
	at org.h2.command.dml.Select.queryFlat(Select.java:744)
	at org.h2.command.dml.Select.queryWithoutCache(Select.java:884)
	at org.h2.command.dml.Query.queryWithoutCacheLazyCheck(Query.java:151)
	at org.h2.command.dml.Query.query(Query.java:435)
	at org.h2.command.dml.Query.query(Query.java:397)
	at org.h2.command.CommandContainer.query(CommandContainer.java:145)
	at org.h2.command.Command.executeQuery(Command.java:202)

~~I’ve not yet had the time to create a minimized test case that reproduces this issue.~~ Update: see my next comment.

opened by bwaldvogel 39

PreparedStatement.executeUpdate() never completes (seqence deadlock)

I'm still trying to figure out exactly the conditions to reproduce this, but under some circumstances (it seems to be more common when doing multi-thread access to an embedded database) a call to PreparedStatement.executeUpdate() on an INSERT effectively locks the VM. No exception ever fires and it would seem that all threads halt. I've written several tests and have been unable to reproduce it except in my application. Any ideas or suggestions on how to further clarify the conditions to reproduce it would be appreciated.

opened by darkfrog26 36

Inline check constraints not in INFORMATION_SCHEMA.CONSTRAINTS

When I create a table as follows:

CREATE TABLE t_6567 (
  a VARCHAR(1) CHECK (a IN ('A', 'B', 'C'))
)

The check constraint is reported in INFORMATION_SCHEMA.COLUMNS (apparently also as part of the COLUMN_TYPE):

TABLE_CATALOG|TABLE_SCHEMA|TABLE_NAME|COLUMN_NAME|ORDINAL_POSITION|DOMAIN_CATALOG|DOMAIN_SCHEMA|DOMAIN_NAME|COLUMN_DEFAULT|IS_NULLABLE|DATA_TYPE|CHARACTER_MAXIMUM_LENGTH|CHARACTER_OCTET_LENGTH|NUMERIC_PRECISION|NUMERIC_PRECISION_RADIX|NUMERIC_SCALE|DATETIME_PRECISION|INTERVAL_TYPE|INTERVAL_PRECISION|CHARACTER_SET_NAME|COLLATION_NAME|TYPE_NAME|NULLABLE|IS_COMPUTED|SELECTIVITY|CHECK_CONSTRAINT       |SEQUENCE_NAME|REMARKS|SOURCE_DATA_TYPE|COLUMN_TYPE                             |COLUMN_ON_UPDATE|IS_VISIBLE|
-------------|------------|----------|-----------|----------------|--------------|-------------|-----------|--------------|-----------|---------|------------------------|----------------------|-----------------|-----------------------|-------------|------------------|-------------|------------------|------------------|--------------|---------|--------|-----------|-----------|-----------------------|-------------|-------|----------------|----------------------------------------|----------------|----------|
TEST         |PUBLIC      |T_6567    |A          |               1|              |             |           |              |YES        |       12|                       1|                     1|                1|                     10|            0|                  |             |                  |Unicode           |OFF           |VARCHAR  |       1|false      |         50|("A" IN('A', 'B', 'C'))|             |       |                |VARCHAR(1) CHECK ("A" IN('A', 'B', 'C'))|                |TRUE      |

but not in INFORMATION_SCHEMA.CONSTRAINTS.

Is this by design or a bug?

opened by knutwannheden 35

Issue#646 NPE in CREATE VIEW WITH RECURSIVE & NON_RECURSIVE CTE

See https://github.com/h2database/h2database/issues/645

I fixed ALL CTE NPE and test cases - and put a fix in for lazy execution/stack overflow by turning that feature off for recursive queries..

opened by stumc 35
Identifiers length limit causes regression when updating H2 from 1.4.200 to 2.0.206

Hey

After upgrading the library due to the security breach, parts of our product broke because of the new limitation on identifiers [256 chars limit] It is similar to https://github.com/h2database/h2database/issues/3359, but in this case it is table definition and not content, which we think is more critical

We would appreciate a resolution here, E.g. having a property to control it

Thanks!

opened by OronSubayi 32

MySQL mode `show columns from table`, if `modificationMetaId` changed between prepared and execute. Then error eccored.

I found these code in Prepared

    public boolean needRecompile() {
        Database db = session.getDatabase();
        if (db == null) {
            throw DbException.get(ErrorCode.CONNECTION_BROKEN_1, "database closed");
        }
        // parser: currently, compiling every create/drop/... twice
        // because needRecompile return true even for the first execution
        return prepareAlways ||
                modificationMetaId < db.getModificationMetaId() ||
                db.getSettings().recompileAlways;
    }

if modificationMetaId changed between prepared and execute. show columns from table will change to

SELECT C.COLUMN_NAME                                    FIELD,
       DATA_TYPE_SQL(?2, ?1, 'TABLE', C.DTD_IDENTIFIER) TYPE,
       C.IS_NULLABLE                                    "NULL",
       CASE (SELECT MAX(I.INDEX_TYPE_NAME)
             FROM INFORMATION_SCHEMA.INDEXES I
                      JOIN INFORMATION_SCHEMA.INDEX_COLUMNS IC
             WHERE I.TABLE_SCHEMA = C.TABLE_SCHEMA
               AND I.TABLE_NAME = C.TABLE_NAME
               AND IC.TABLE_SCHEMA = C.TABLE_SCHEMA
               AND IC.TABLE_NAME = C.TABLE_NAME
               AND IC.INDEX_SCHEMA = I.INDEX_SCHEMA
               AND IC.INDEX_NAME = I.INDEX_NAME
               AND IC.COLUMN_NAME = C.COLUMN_NAME)
           WHEN 'PRIMARY KEY' THEN 'PRI'
           WHEN 'UNIQUE INDEX' THEN 'UNI'
           ELSE '' END                                  `KEY`,
       COALESCE(COLUMN_DEFAULT, 'NULL')                 `DEFAULT`
FROM INFORMATION_SCHEMA.COLUMNS C
WHERE C.TABLE_NAME = ?1
  AND C.TABLE_SCHEMA = ?2
ORDER BY C.ORDINAL_POSITION

but recompile has no parameters. Then errors occured like this.

Caused by: java.lang.IndexOutOfBoundsException: Index: 1, Size: 0
 at java.util.ArrayList.rangeCheck(ArrayList.java:659)
 at java.util.ArrayList.get(ArrayList.java:435)
 at org.h2.command.Parser.readParameter(Parser.java:4877)
 at org.h2.command.Parser.readTermWithoutIdentifier(Parser.java:4950)
 at org.h2.command.Parser.readTerm(Parser.java:4901)
 at org.h2.command.Parser.readFactor(Parser.java:3398)
 at org.h2.command.Parser.readSum(Parser.java:3385)
 at org.h2.command.Parser.readConcat(Parser.java:3350)
 at org.h2.command.Parser.readCondition(Parser.java:3132)
 at org.h2.command.Parser.readExpression(Parser.java:3053)
 at org.h2.command.Parser.readBuiltinFunctionIf(Parser.java:4391)
 at org.h2.command.Parser.readFunction(Parser.java:3829)
 at org.h2.command.Parser.readTermWithIdentifier(Parser.java:5211)
 at org.h2.command.Parser.readTerm(Parser.java:4901)
 at org.h2.command.Parser.readFactor(Parser.java:3398)
 at org.h2.command.Parser.readSum(Parser.java:3385)
 at org.h2.command.Parser.readConcat(Parser.java:3350)
 at org.h2.command.Parser.readCondition(Parser.java:3132)
 at org.h2.command.Parser.readExpression(Parser.java:3053)
 at org.h2.command.Parser.parseSelectExpressions(Parser.java:2853)
 at org.h2.command.Parser.parseSelect(Parser.java:2871)
 at org.h2.command.Parser.parseQueryPrimary(Parser.java:2762)
 at org.h2.command.Parser.parseQueryTerm(Parser.java:2633)
 at org.h2.command.Parser.parseQueryExpressionBody(Parser.java:2612)
 at org.h2.command.Parser.parseQueryExpressionBodyAndEndOfQuery(Parser.java:2605)
 at org.h2.command.Parser.parseQueryExpression(Parser.java:2598)
 at org.h2.command.Parser.parseQuery(Parser.java:2567)
 at org.h2.command.Parser.parsePrepared(Parser.java:724)
 at org.h2.command.Parser.parse(Parser.java:689)
 at org.h2.command.Parser.parse(Parser.java:661)
 at org.h2.command.CommandContainer.recompileIfRequired(CommandContainer.java:146)
 at org.h2.command.CommandContainer.query(CommandContainer.java:247)
 at org.h2.command.Command.executeQuery(Command.java:190)

opened by tuichenchuxin 0

BACKUP command backups already corrupted file

My program periodically creates backups using the BACKUP command. These files are deleted after 4 weeks.

When trying to migrate to v2 I now have found out that my current database file is corrupt (File corrupted while reading record: "type: 221". Possible solution: use the recovery tool [90030-200]).

Going through my backup files I realize that all of them are also corrupt even though the BACKUP command was executed without errors. I would've though that the backup does some kind of integrity check. I now have weeks of backups and can't use any of them because the corrupted database was backed up.

The recovery tool successfully creates a huge TXT file and a small SQL file but running the SQL file does not help, the file is still corrupted.

So I could be running an instance with a corrupted database without knowing it and even making backups from it without knowing it. Running SCRIPT TO raises an error for the corrupt database so perhaps I could do that but it takes longer and this problem kinda defeats the idea of a backup, I think.

opened by theotherp 1

MVStoreException when running `SELECT ... FOR UPDATE`

In our project we're testing a piece of code which uses SELECT ... FOR UPDATE to lock acquire a lock.

In a test we're running a concurrent test and when running on Git Hub Workflows, it fails randomly but regularly (~10-25% of the time). When running it locally, even when having it bound to a single core, it doesn't fail.

We're using an in-memory database (jdbc:h2:mem:test;DB_CLOSE_DELAY=-1) with H2 2.1.214. Looking back, the problem might have started when we upgraded from H2 1.4.197 to 2.1.214.

The SQL (run on each thread) is to find out if the table DATABASECHANGELOGLOCK exists, if not create the table, insert some rows to run the SELECT ... FOR UPDATE on, and then finally run the SELECT ... FOR UPDATE and create a DB schema.

// does the table exist?
SELECT COUNT(*) FROM PUBLIC.DATABASECHANGELOGLOCK

// if not, create the table, then commit. If creation of the table fails due to concurrency, roll back the transaction, and retry from the start.
CREATE TABLE PUBLIC.DATABASECHANGELOGLOCK (ID INT NOT NULL, LOCKED BOOLEAN NOT NULL, LOCKGRANTED TIMESTAMP, LOCKEDBY VARCHAR(255), CONSTRAINT PK_DATABASECHANGELOGLOCK PRIMARY KEY (ID))

// do the entries exist? 
SELECT ID FROM PUBLIC.DATABASECHANGELOGLOCK

// if not, create the entries, then commit. If inserting of the entries fails due to concurrency, roll back the transaction, and retry from the start
INSERT INTO PUBLIC.DATABASECHANGELOGLOCK (ID, LOCKED) VALUES (1, FALSE)
INSERT INTO PUBLIC.DATABASECHANGELOGLOCK (ID, LOCKED) VALUES (1000, FALSE)
INSERT INTO PUBLIC.DATABASECHANGELOGLOCK (ID, LOCKED) VALUES (1001, FALSE)

// lock the entry
SELECT ID FROM PUBLIC.DATABASECHANGELOGLOCK WHERE ID=1 FOR UPDATE

// lots of schema creation statements
CREATE TABLE ...
CREATE TABLE ...
CREATE TABLE ...
CREATE TABLE ...

The error:

Caused by: org.h2.jdbc.JdbcSQLNonTransientConnectionException: The database has been closed; SQL statement:
SELECT ID FROM PUBLIC.DATABASECHANGELOGLOCK WHERE ID=1001 FOR UPDATE [90098-214]
   at org.h2.message.DbException.getJdbcSQLException(DbException.java:678)
   at org.h2.message.DbException.getJdbcSQLException(DbException.java:477)
   at org.h2.message.DbException.get(DbException.java:212)
   at org.h2.mvstore.db.Store.convertMVStoreException(Store.java:159)
   at org.h2.mvstore.db.MVTable.convertException(MVTable.java:687)
   at org.h2.mvstore.db.MVPrimaryIndex.lockRow(MVPrimaryIndex.java:224)
   at org.h2.mvstore.db.MVPrimaryIndex.lockRow(MVPrimaryIndex.java:217)
   at org.h2.mvstore.db.MVTable.lockRow(MVTable.java:555)
   at org.h2.command.query.Select.isConditionMetForUpdate(Select.java:432)
   at org.h2.command.query.Select$LazyResultQueryFlat.fetchNextRow(Select.java:1835)
   at org.h2.result.LazyResult.hasNext(LazyResult.java:78)
   at org.h2.result.FetchedResult.next(FetchedResult.java:34)
   at org.h2.command.query.Select.queryFlat(Select.java:728)
   at org.h2.command.query.Select.queryWithoutCache(Select.java:833)
   at org.h2.command.query.Query.queryWithoutCacheLazyCheck(Query.java:197)
   at org.h2.command.query.Query.query(Query.java:512)
   at org.h2.command.query.Query.query(Query.java:475)
   at org.h2.command.CommandContainer.query(CommandContainer.java:251)
   at org.h2.command.Command.executeQuery(Command.java:190)
   at org.h2.jdbc.JdbcStatement.executeInternal(JdbcStatement.java:247)
   at org.h2.jdbc.JdbcStatement.execute(JdbcStatement.java:223)
   at liquibase.executor.jvm.JdbcExecutor$ExecuteStatementCallback.doInStatement(JdbcExecutor.java:436)
   ... 18 more
Caused by: org.h2.mvstore.MVStoreException: Map null(1258) is closed. null [2.1.214/4]
   at org.h2.mvstore.DataUtils.newMVStoreException(DataUtils.java:1004)
   at org.h2.mvstore.MVMap.beforeWrite(MVMap.java:962)
   at org.h2.mvstore.MVMap.operate(MVMap.java:1757)
   at org.h2.mvstore.tx.TransactionMap.set(TransactionMap.java:346)
   at org.h2.mvstore.tx.TransactionMap.lock(TransactionMap.java:312)
   at org.h2.mvstore.db.MVPrimaryIndex.lockRow(MVPrimaryIndex.java:222)
   ... 34 more

Due to this, the DB panics and closes the DB.

The next DB connection which is opened then sees an empty DB (as it is an in-memory database).

opened by ahus1 4

Password exposure in H2 Database

Dependabot and org.owasp:dependency-check-maven have been reporting CVE-2022-45868 (see https://github.com/advisories/GHSA-22wj-vf5f-wrvj) to me. I didn't find this CVE referenced from any issue in the issue tracker here, so I'm creating this one.

Since the CVE has already been published (possibly as a zero day??), I suspect it's OK to report this publicly here. For future private reports, I think it would be useful to set up a security policy here on github? https://github.com/h2database/h2database/security/policy

opened by lukaseder 6
No new Artefact published since June

We are currently running version https://mvnrepository.com/artifact/com.h2database/h2/2.1.214

Our project urgently requires this Pull Request which was merged months ago https://github.com/h2database/h2database/pull/3579

Please can this artefact be published :) The alternative for us is unfortunately reluctantly removing H2 from our project :/

opened by ed0906 3
Parenthesised join tables can't have derived columns lists
Possibly related to https://github.com/h2database/h2database/issues/3680, might be a duplicate, but manifests differently.

This query works:

select * from ((values (1)) cross join (values (2))) as x;

Producing:

|C1 |C1 | |---|---| |1 |2 |

Adding a derived column list doesn't work:

select * from ((values (1)) cross join (values (2))) as x (a, b);

It raises:

SQL Error [21002] [21S02]: Column count does not match; SQL statement: select * from ((values (1)) cross join (values (2))) as x (a, b) [21002-214]

Curiously, this works, though it shouldn't:

select * from ((values (1)) cross join (values (2))) as x (a);

It renames only the first column

|A |C1 | |---|---| |1 |2 |

Which is why this looks as though it could be a different manifestation of #3680
opened by lukaseder 0

Releases(version-2.1.214)

version-2.1.214(Jun 14, 2022)
Important bugs and regression fixes discovered after 2.1.212 release:

Issue #3538: In Postgres compatibility mode the NUMERIC type w/o scale should not default to 0

Issue #3534: Subquery has incorrect empty parameters since 2.1.210 that breaks sameResultAsLast()

Issue #3390: "ROW" cannot be set as a non keyword in 2.x

Issue #3448: With linked table to postgreSQL, case-sensitive column names not respected in where part

Issue #3434: JavaTableFunction is not closing underlying ResultSet when reading column list

Issue #3468: Invalid DB format exception (for 1.x DB in 2.x h2) should have a specific SQLException vendorCode

Issue #3528: Weird syntax error with HAVING clause in Oracle Mode

Issue #3307: Fix SHUTDOWN DEFRAG for encrypted databases

Issue #3515: Support for NEXTVAL property in DB2 mode

Issue #3444: Conversion 'text' to 'integer' doesn't work anymore

Issue #3493: org.h2.tools.DeleteDbFiles won't delete files under certain circumstances

Issue #3486: FilePathDisk.newDirectoryStream() may fail on remote drive on Windows due to AccessDeniedException in Path.toRealPath()

Issue #3484: LOB issue

Source code(tar.gz)
Source code(zip)
bundle-mvstore.jar(692.62 KB)
bundle.jar(7.21 MB)
h2-2.1.214-javadoc.jar(3.46 MB)
h2-2.1.214-sources.jar(1.63 MB)
h2-2.1.214.jar(2.42 MB)
h2-2022-06-13.zip(9.50 MB)
h2-mvstore-2.1.214-javadoc.jar(231.22 KB)
h2-mvstore-2.1.214-sources.jar(161.29 KB)
h2-mvstore-2.1.214.jar(325.59 KB)
h2-setup-2022-06-13.exe(6.55 MB)
h2.pdf(1.92 MB)
version-2.1.212(Apr 9, 2022)
Numerous bugs and regression fixes discovered after 2.1.210 release:

PR #3481: Add support for standard interval literals with precision

Issue #3471: Possibility of corruption after SHUTDOWN DEFRAG

Issue #3473: DROP TABLE/INDEX causes memory leak

PR #3464 / Issue #3457: increase max length of VAR* types

PR #3460: fix bug in readStoreHeader()

PR #3458: Add performance tests for SQLite

Issue #1808: Occasional NPE in concurrent update of LOB

Issue #3439: Cannot use enum values in JSON without explicit casts

Issue #3426: Regression: BIT(1) is not accepted in MySQL compatibility mode

PR #3422: Allow combination of any geometry types with the same SRID

Issue #3414: H2 2.1.210: Query with Parameters throws NPE

Issue #3413: Parser can't parse REFERENCES … NOT NULL

Issue #3410: OOME with nested derived tables

Issue #3405: Enhance SCRIPT to support operations on STDOUT

Issue #3406 / PR #3407: FunctionMultiReturn.polar2CartesianArray result set iteration throws ClassCastException

Issue #3400: Regression: ORDER BY ROWNUM fails with General error: "Unexpected code path"

Issue #3387: SYSDATE behavior changed in 2.x

Issue #3394: SYSDATE Considered Identifier when used in inner select

Issue #3391: Hang on merge statement with data change delta table

PR #3384: Remove abandoned Java to C converter and fix some warnings from Sonatype Lift

PR #3382: Use secure parser in H2AuthConfigXml to avoid future reports

Source code(tar.gz)
Source code(zip)
h2-2.1.212-javadoc.jar(3.46 MB)
h2-2.1.212-sources.jar(1.62 MB)
h2-2.1.212.jar(2.42 MB)
h2-2022-04-09.zip(9.52 MB)
h2-mvstore-2.1.212-javadoc.jar(233.46 KB)
h2-mvstore-2.1.212-sources.jar(161.54 KB)
h2-mvstore-2.1.212.jar(324.53 KB)
h2-setup-2022-04-09.exe(6.56 MB)
h2.pdf(1.92 MB)
version-2.1.210(Jan 17, 2022)
Two security vulnerabilities in H2 Console (CVE-2022-23221 and possible DNS rebinding attack) are fixed.

Persistent databases created by H2 2.0.x don't need to be upgraded. Persistent databases created by H2 1.4.200 and older versions require export into SQL script with that old version and creation of a new database with the new version and execution of this script in it.

PR #3381: Add IDENTITY() and SCOPE_IDENTITY() to LEGACY mode

Issue #3376: Data cannot be read after insert of clob data > MAX_LENGTH_INPLACE_LOB with data change delta table

PR #3377: Add -webExternalNames setting and fix WebServer.getConnection()

PR #3367: Use faster checks of dimension systems of geometries

PR #3369: Added v2 changes in migration docs

Issue #3361: MemoryEstimator.estimateMemory() can return negative size

PR #3362: Use BufferedReader instead of BufferedInputStream to avoid Illegal seek exception

Issue #3353: Wrong rownum() scope for DML with change delta table

PR #3352: make Javadoc happier

Issue #3344: Changelog could link to github issue

Issue #3340: JDBC index type seems wrong

Issue #3336: FT_INIT error when mode=MySQL

Issue #3334: Regression with CREATE ALIAS - Parameter "#2" is not set

Issue #3321: Insert Primary Key after import CSV Data does not work

PR #3323: Tokenize SQL before parsing and preserve tokens for recompilation

PR #3320: Add Servlet 5-compatible servlet for H2 Console

Issue #918: Parser fails recognising set operations in correlated subqueries

Issue #2050: PostgreSQL with recursive fail with union in the final query

PR #3316: Update copyright years

PR #3315: Never put read locks into lockSharedSessions and other minor changes

Issue #492: H2 does not correctly parse <parenthesized joined table>

Issue #3311: Parser creates wrong join graph in some cases and uses wrong tables for column mapping

FORCE_JOIN_ORDER setting is removed

Issue #1983: Official build script is not compatible with Java 13

PR #3305: Add UNIQUE(VALUE) and remove some non-standard keywords

PR #3299: Remove useless StringBuilder.toString() call

PR #3298: Delete unused sqlTypes array

Source code(tar.gz)
Source code(zip)
h2-2.1.210-javadoc.jar(3.44 MB)
h2-2.1.210-sources.jar(1.62 MB)
h2-2.1.210.jar(2.41 MB)
h2-2022-01-17.zip(9.53 MB)
h2-mvstore-2.1.210-javadoc.jar(230.73 KB)
h2-mvstore-2.1.210-sources.jar(160.88 KB)
h2-mvstore-2.1.210.jar(2.08 MB)
h2-setup-2022-01-17.exe(6.57 MB)
h2.pdf(1.92 MB)
version-2.0.206(Jan 4, 2022)
Critical security issue with H2 console is fixed.

Also important changes included:

Issue #3322: Create linked table fails when the table contains a Geometry with a data type specified

Issue #3297: Unexpected GROUP BY results with indexed IGNORECASE column

Source code(tar.gz)
Source code(zip)
h2-2.0.206-javadoc.jar(3.44 MB)
h2-2.0.206-sources.jar(1.61 MB)
h2-2.0.206.jar(2.39 MB)
h2-2022-01-04.zip(9.09 MB)
h2-mvstore-2.0.206-javadoc.jar(230.75 KB)
h2-mvstore-2.0.206-sources.jar(160.54 KB)
h2-mvstore-2.0.206.jar(2.07 MB)
h2-setup-2022-01-04.exe(6.42 MB)
h2.pdf(1.91 MB)
version-2.0.204(Dec 21, 2021)

Multilple regression fixes discovered after 2.0.202 release,

There are no persistence changes between 2.0.202 and 2.0.204, so jar file swap is enough, if database had been upgraded to 2.0.202 already, otherwise please read the message below:

Between version 1.4.200 and version 2.0.202 there have been considerable changes, such that a simple update is not possible. The official way to upgrade is to do a BACKUP of your existing database USING YOUR CURRENT VERSION OF H2. Then create a fresh database USING THE NEW VERSION OF H2, then perform a SCRIPT to load your data.
Source code(tar.gz)
Source code(zip)
bundle-mvstore.jar(2.34 MB)
bundle.jar(7.15 MB)
h2-2.0.204-javadoc.jar(3.44 MB)
h2-2.0.204-sources.jar(1.61 MB)
h2-2.0.204.jar(2.39 MB)
h2-2021-12-21.zip(9.09 MB)
h2-mvstore-2.0.204-javadoc.jar(230.58 KB)
h2-mvstore-2.0.204-sources.jar(160.54 KB)
h2-mvstore-2.0.204.jar(2.07 MB)
h2-setup-2021-12-21.exe(6.42 MB)
h2.pdf(1.91 MB)
version-2.0.202(Nov 26, 2021)
Besides many dozens of fixed bugs, performance improvements, more adherence to a standard SQL syntax and type system, there are

Some new features:

Complete re-work of INFORMATION_SCHEMA to be more in-line with the standard

Support for new types: ARRAY, ROW, JAVA_OBJECT

Numerous bit, string, array and system functions implemented

Standard-based access to generated keys

JDBC 4.2 compliance

Support for JDK 7 is dropped

PageStore is discontinued

MVStore changes:

Descending MVMap and TransactionMap cursor

Disk space reclamation algorithm improvements

Between version 1.4.200 and version 2.0.202 there have been considerable changes, such that a simple update is not possible. The official way to upgrade is to do a BACKUP of your existing database USING YOUR CURRENT VERSION OF H2. Then create a fresh database USING THE NEW VERSION OF H2, then perform a SCRIPT to load your data.
Source code(tar.gz)
Source code(zip)
h2-2.0.202-javadoc.jar(3.44 MB)
h2-2.0.202-sources.jar(1.61 MB)
h2-2.0.202.jar(2.39 MB)
h2-2021-11-25.zip(9.11 MB)
h2-mvstore-2.0.202-javadoc.jar(227.27 KB)
h2-mvstore-2.0.202-sources.jar(160.02 KB)
h2-mvstore-2.0.202.jar(323.05 KB)
h2-setup-2021-11-25.exe(6.42 MB)
h2.pdf(1.91 MB)
version-1.4.200(Oct 14, 2019)
Some new features:

JSON data type; JSON_OBJECT, JSON_ARRAY, JSON_OBJECTAGG, and JSON_ARRAYAGG functions; JSON predicate

TIME WITH TIME ZONE data type

BITNOT, LSHIFT, and RSHIFT functions

Named columns join (JOIN USING)

Type predicate (IS OF)

General logarithm function LOG(base, argument)

UNKNOWN literal and truth value tests

UNIQUE predicate

CURRENT_SCHEMA and CURRENT_CATALOG functions

Data change delta tables (OLD | NEW | FINAL TABLE)

CURRENT VALUE FOR sequence

EXECUTE IMMEDIATE command and QUOTE_IDENT function

Time zone specification (AT TIME ZONE | LOCAL)

ALTER TABLE tableName ALTER COLUMN IF EXISTS columnName

READ UNCOMMITTED, REPEATABLE READ, and SNAPSHOT isolation levels with MVStore engine

Incompatible changes:

MVCC setting (ignored since 1.4.198) now results in error

MULTI_THREADED setting is removed, MVStore engine is always multi-threaded, PageStore engine is always single-threaded

Statement.getGeneratedKeys() uses a FINAL TABLE semantics

Proleptic Gregorian calendar is used as required by the Standard

Cast from TIME to TIMESTAMP uses CURRENT_DATE for a date part as required by the Standard

Other changes:

Scalability and stability of MVStore engine are improved

Assorted bugfixes

Known issues:

Asterisked projection of NATURAL join and named columns join between three or more tables can still be incorrect, use qualified column names as a workaround

SERIALIZABLE isolaton level can be set, but it doesn't ensure equivalence of serial and concurrent execution of transactions

See change log for the complete list of changes.
Source code(tar.gz)
Source code(zip)
version-1.4.199(Mar 13, 2019)

Bugfixes
Source code(tar.gz)
Source code(zip)
version-1.4.198(Feb 22, 2019)
Features

Experimental implementation of all standard window functions.

Hypothetical set and inverse distribution functions.

INTERVAL data type.

Geomety type and SRID constraints for GEOMETRY data type, M and ZM dimension systems support.

WITH TIES and PERCENT support in FETCH clause.

SELECT DISTINCT ON(expression, …).

java.sql.SQLXML support.

Standard systax for ARRAY values, some new functions.

Performance

MVStore engine is multi-threaded by default.

Performance and atomicity improvements in MVStore engine.

Large result sets are processed faster with MVStore engine.

Large OFFSET values are faster in some queries.

Other

TCP Server and H2 Console do not allow creation of new databases by default.

MVCC setting is removed, MVStore uses row-level locks and PageStore uses table-level locks unconditionally.

MVStore jar incudes TransactionStore too.

Lucene is upgraded to 5.5.5-7.6.0.

JTS library is not required any more for GEOMETRY data type.

h2.oldStyleOuterJoin setting and limited support for archaic (+) outer joins are removed.

See change log for a complete list of changes.
Source code(tar.gz)
Source code(zip)
version-1.4.197(Mar 18, 2018)

See release notes
Source code(tar.gz)
Source code(zip)
version-1.4.196(Jun 10, 2017)

Bugfixes and some new features
Source code(tar.gz)
Source code(zip)
version-1.4.195(Apr 23, 2017)

Bugfixes and some new features
Source code(tar.gz)
Source code(zip)
version-1.4.194(Mar 10, 2017)

Various bugfixes and new features
Source code(tar.gz)
Source code(zip)
version-1.4.193(Oct 31, 2016)

Many bugfixes, and slightly increased concurrency.
Source code(tar.gz)
Source code(zip)
version-1.4.192(May 26, 2016)

Improved performance, new experimental data type "timestamp with timezone", improved SQL syntax.
Source code(tar.gz)
Source code(zip)
version-1.4.191(Jan 21, 2016)

Bugfixes and minor new features.
Source code(tar.gz)
Source code(zip)
version-1.4.190(Oct 11, 2015)

MVStore: CLOB and BLOB cleanup
Source code(tar.gz)
Source code(zip)
version-1.4.188(Aug 1, 2015)

MVStore: protection against power failure and out of memory.
Source code(tar.gz)
Source code(zip)