Hi David, That's definitely unexpected. It sounds like your database upgrade succeeded, but failed to update your local DSpace registries (the Bitstream Format Registry). This syntax error is very odd, and unfortunately is nothing I've seen before:
2019-03-01 13:09:16,849 WARN org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ SQL Error: 0, SQLState: 42601 2019-03-01 13:09:16,849 ERROR org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ Batch entry 0 delete from .webapp where webapp_id=396 was aborted: ERROR: syntax error at or near "." Position: 13 Call getNextException to see other errors in the batch. I'm hoping that the primary issue is that your registries didn't update properly. But, you also may want to look back at the logs for the database upgrade itself, to see if any other strange warnings or errors are noted there. If it's a simple registry issue, you should be able to manually update your registries as noted in Step 11 (part "d") of the Upgrade Docs: https://wiki.duraspace.org/display/DSDOC6x/Upgrading+DSpace If that manual update of the registries doesn't work, then I highly suspect some (other) part of the upgrade didn't complete successfully. You should look back at the logs (or try the upgrade again on a test server, closely watching the logs). You also could verify your database is reporting successful upgrades by checking the output of "./dspace database info". I also just found this old email thread that mentioned this same error. The suggestion in that thread was to check your settings for "db.schema" (which for PostgreSQL should be set to "public"): http://dspace.2283337.n4.nabble.com/DSpace6-SQL-Error-td4684728.html Tim On Fri, Mar 1, 2019 at 12:53 PM David Baker <[email protected]> wrote: > Thanks, Tim. I deleted the duplicate COLLECTION_29_DEFAULT_READ group and > the migration proceeded past. It now shows all of the migrations as > successful except DS-2818 registry update, which I'm assuming is normal. > > I'm apparently having an issue somewhere else now, though. When I browse > to my repository, I only get a blank white screen. The end of my dspace.log > file is showing a few errors: > > > 2019-03-01 12:57:19,683 INFO > org.dspace.importer.external.service.ImportService @ Loading 1 import > sources. > 2019-03-01 12:57:30,980 INFO org.dspace.core.LoggerServiceImpl @ Using > dspace provided log configuration (log.init.config) > 2019-03-01 12:57:30,996 INFO org.dspace.core.LoggerServiceImpl @ Loading: > C:\dspace/config/log4j.properties > 2019-03-01 12:57:35,058 INFO org.dspace.storage.rdbms.DatabaseUtils @ > Loading Flyway DB migrations from: filesystem:C:\dspace/etc/postgres, > classpath:org.dspace.storage.rdbms.sqlmigration.postgres, > classpath:org.dspace.storage.rdbms.migration > 2019-03-01 12:57:35,449 INFO > org.flywaydb.core.internal.util.VersionPrinter @ Flyway 4.0.3 by Boxfuse > 2019-03-01 12:57:35,621 INFO > org.flywaydb.core.internal.dbsupport.DbSupportFactory @ Database: > jdbc:postgresql://localhost:5432/dspace (PostgreSQL 9.4) > 2019-03-01 12:57:49,574 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2015.08.31 - DS 2701 Hibernate > Workflow Migration > 2019-03-01 12:57:49,887 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.01.03 - DS-3024 > 2019-03-01 12:57:50,699 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.01.26 - DS 2188 Remove DBMS > Browse Tables > 2019-03-01 12:57:50,918 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.02.25 - > DS-3004-slow-searching-as-admin > 2019-03-01 12:57:50,949 INFO > org.flywaydb.core.internal.dbsupport.JdbcTemplate @ DB: column "name" of > relation "epersongroup" does not exist, skipping > 2019-03-01 12:57:51,012 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.04.01 - DS-1955 Increase > embargo reason > 2019-03-01 12:57:51,090 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.04.04 - > DS-3086-OAI-Performance-fix > 2019-03-01 12:57:52,090 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.04.14 - > DS-3125-fix-bundle-bitstream-delete-rights > 2019-03-01 12:57:52,418 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.05.10 - > DS-3168-fix-requestitem item id column > 2019-03-01 12:57:52,512 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.07.21 - DS-2775 > 2019-03-01 12:57:52,605 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.07.26 - DS-3277 fix handle > assignment > 2019-03-01 12:57:52,902 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.0.2016.08.23 - DS-3097 > 2019-03-01 12:57:53,168 INFO org.flywaydb.core.internal.command.DbMigrate > @ Migrating schema "public" to version 6.1.2017.01.03 - DS 3431 Add > Policies for BasicWorkflow > 2019-03-01 12:57:53,668 INFO org.flywaydb.core.internal.command.DbMigrate > @ Successfully applied 17 migrations to schema "public" (execution time > 02:27.528s). > 2019-03-01 12:57:53,715 INFO org.dspace.event.EventServiceImpl @ > EventService dispatcher pool initialized > 2019-03-01 12:57:54,168 INFO org.hibernate.annotations.common.Version @ > HCANN000001: Hibernate Commons Annotations {4.0.2.Final} > 2019-03-01 12:57:54,262 INFO org.hibernate.Version @ HHH000412: Hibernate > Core {4.2.21.Final} > 2019-03-01 12:57:54,277 INFO org.hibernate.cfg.Environment @ HHH000206: > hibernate.properties not found > 2019-03-01 12:57:54,277 INFO org.hibernate.cfg.Environment @ HHH000021: > Bytecode provider name : javassist > 2019-03-01 12:57:54,340 INFO org.hibernate.cfg.Configuration @ HHH000044: > Configuring from URL: file:/dspace/config/hibernate.cfg.xml > 2019-03-01 12:57:54,605 INFO org.hibernate.cfg.Configuration @ HHH000041: > Configured SessionFactory: null > 2019-03-01 12:57:55,168 INFO org.hibernate.dialect.Dialect @ HHH000400: > Using dialect: > org.dspace.storage.rdbms.hibernate.postgres.DSpacePostgreSQL82Dialect > 2019-03-01 12:57:55,199 INFO > org.hibernate.engine.jdbc.internal.LobCreatorBuilder @ HHH000424: Disabling > contextual LOB creation as createClob() method threw error : > java.lang.reflect.InvocationTargetException > 2019-03-01 12:57:55,215 INFO org.hibernate.type.BasicTypeRegistry @ > HHH000270: Type registration [pg-uuid] overrides previous : > org.hibernate.type.PostgresUUIDType@7ec8809a > 2019-03-01 12:57:55,215 INFO org.hibernate.type.BasicTypeRegistry @ > HHH000270: Type registration [java.util.UUID] overrides previous : > org.hibernate.type.UUIDBinaryType@c61cc09 > 2019-03-01 12:57:56,777 INFO > org.hibernate.engine.transaction.internal.TransactionFactoryInitiator @ > HHH000399: Using default transaction strategy (direct JDBC transactions) > 2019-03-01 12:57:56,793 INFO > org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory @ HHH000397: Using > ASTQueryTranslatorFactory > 2019-03-01 12:57:56,902 WARN net.sf.ehcache.CacheManager @ Creating a new > instance of CacheManager using the diskStorePath "C:\Program > Files\apache-tomcat-8.5.31\temp" which is already used by an existing > CacheManager. > The source of the configuration was > net.sf.ehcache.config.generator.ConfigurationSource$URLConfigurationSource@4bf6c95b. > The diskStore path for this CacheManager will be set to C:\Program > Files\apache-tomcat-8.5.31\temp\ehcache_auto_created_1551463076902. > To avoid this warning consider using the CacheManager factory methods to > create a singleton CacheManager or specifying a separate ehcache > configuration (ehcache.xml) for each CacheManager instance. > 2019-03-01 12:57:57,105 INFO > org.hibernate.cache.spi.UpdateTimestampsCache @ HHH000250: Starting update > timestamps cache at region: org.hibernate.cache.spi.UpdateTimestampsCache > 2019-03-01 12:57:57,105 INFO > org.hibernate.cache.internal.StandardQueryCache @ HHH000248: Starting query > cache at region: org.hibernate.cache.internal.StandardQueryCache > 2019-03-01 12:57:59,325 INFO org.hibernate.tool.hbm2ddl.SchemaValidator @ > HHH000229: Running schema validator > 2019-03-01 12:57:59,325 INFO org.hibernate.tool.hbm2ddl.SchemaValidator @ > HHH000102: Fetching database metadata > 2019-03-01 12:57:59,418 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.doi > 2019-03-01 12:57:59,418 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [dspace_object, resource_type_id, resource_id, doi_id, > doi, status] > 2019-03-01 12:57:59,418 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.bitstream > 2019-03-01 12:57:59,418 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [deleted, size_bytes, internal_id, checksum_algorithm, > bitstream_id, checksum, store_number, sequence_id, bitstream_format_id, > uuid] > 2019-03-01 12:57:59,434 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.bitstreamformatregistry > 2019-03-01 12:57:59,434 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [short_description, internal, description, > bitstream_format_id, mimetype, support_level] > 2019-03-01 12:57:59,434 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.bundle > 2019-03-01 12:57:59,434 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [primary_bitstream_id, bundle_id, uuid] > 2019-03-01 12:57:59,449 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.bundle2bitstream > 2019-03-01 12:57:59,449 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [bitstream_order, bitstream_order_legacy, bundle_id, > bitstream_id] > 2019-03-01 12:57:59,449 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.checksum_history > 2019-03-01 12:57:59,449 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [result, process_start_date, process_end_date, > bitstream_id, check_id, checksum_calculated, checksum_expected] > 2019-03-01 12:57:59,465 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.checksum_results > 2019-03-01 12:57:59,465 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [result_description, result_code] > 2019-03-01 12:57:59,465 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.collection > 2019-03-01 12:57:59,465 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, submitter, logo_bitstream_id, > workflow_step_3, admin, workflow_step_1, workflow_step_2, template_item_id, > uuid] > 2019-03-01 12:57:59,480 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.collection2item > 2019-03-01 12:57:59,480 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, item_id] > 2019-03-01 12:57:59,480 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.community > 2019-03-01 12:57:59,480 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [logo_bitstream_id, community_id, admin, uuid] > 2019-03-01 12:57:59,480 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.community2collection > 2019-03-01 12:57:59,480 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, community_id] > 2019-03-01 12:57:59,496 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.community2community > 2019-03-01 12:57:59,496 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [parent_comm_id, child_comm_id] > 2019-03-01 12:57:59,496 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.dspaceobject > 2019-03-01 12:57:59,496 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [uuid] > 2019-03-01 12:57:59,496 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.eperson > 2019-03-01 12:57:59,496 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [sub_frequency, password, can_log_in, digest_algorithm, > salt, eperson_id, netid, last_active, self_registered, uuid, email, > require_certificate] > 2019-03-01 12:57:59,512 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.epersongroup > 2019-03-01 12:57:59,512 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [permanent, name, eperson_group_id, uuid] > 2019-03-01 12:57:59,699 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.epersongroup2eperson > 2019-03-01 12:57:59,699 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [eperson_id, eperson_group_id] > 2019-03-01 12:57:59,699 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.epersongroup2workspaceitem > 2019-03-01 12:57:59,730 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [eperson_group_id, workspace_item_id] > 2019-03-01 12:57:59,730 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.fileextension > 2019-03-01 12:57:59,730 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [file_extension_id, extension, bitstream_format_id] > 2019-03-01 12:57:59,730 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.group2group > 2019-03-01 12:57:59,730 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [child_id, parent_id] > 2019-03-01 12:57:59,746 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.group2groupcache > 2019-03-01 12:57:59,746 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [child_id, parent_id] > 2019-03-01 12:57:59,746 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.handle > 2019-03-01 12:57:59,746 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [handle_id, resource_legacy_id, resource_type_id, > resource_id, handle] > 2019-03-01 12:57:59,746 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.harvested_collection > 2019-03-01 12:57:59,746 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, oai_source, metadata_config_id, > last_harvested, oai_set_id, harvest_type, harvest_status, id, > harvest_message, harvest_start_time] > 2019-03-01 12:57:59,746 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.harvested_item > 2019-03-01 12:57:59,746 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [oai_id, last_harvested, item_id, id] > 2019-03-01 12:57:59,762 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.item > 2019-03-01 12:57:59,762 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [item_id, discoverable, in_archive, withdrawn, > submitter_id, last_modified, uuid, owning_collection] > 2019-03-01 12:57:59,762 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.item2bundle > 2019-03-01 12:57:59,762 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [item_id, bundle_id] > 2019-03-01 12:57:59,762 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.metadatafieldregistry > 2019-03-01 12:57:59,762 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [scope_note, metadata_schema_id, qualifier, > metadata_field_id, element] > 2019-03-01 12:57:59,840 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.metadataschemaregistry > 2019-03-01 12:57:59,840 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [metadata_schema_id, namespace, short_id] > 2019-03-01 12:57:59,902 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.metadatavalue > 2019-03-01 12:57:59,902 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [text_value, dspace_object_id, text_lang, authority, > confidence, metadata_value_id, place, metadata_field_id] > 2019-03-01 12:57:59,919 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.most_recent_checksum > 2019-03-01 12:57:59,919 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [last_process_end_date, result, expected_checksum, > matched_prev_checksum, to_be_processed, current_checksum, > checksum_algorithm, bitstream_id, last_process_start_date] > 2019-03-01 12:57:59,934 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.registrationdata > 2019-03-01 12:57:59,934 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [registrationdata_id, expires, email, token] > 2019-03-01 12:57:59,949 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.requestitem > 2019-03-01 12:57:59,949 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [request_name, decision_date, expires, item_id, > request_message, request_email, request_date, bitstream_id, allfiles, > accept_request, requestitem_id, token] > 2019-03-01 12:57:59,949 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.resourcepolicy > 2019-03-01 12:57:59,949 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [end_date, dspace_object, rptype, resource_type_id, > policy_id, eperson_id, action_id, epersongroup_id, rpdescription, > resource_id, start_date, rpname] > 2019-03-01 12:57:59,965 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.site > 2019-03-01 12:57:59,965 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [uuid] > 2019-03-01 12:57:59,965 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.subscription > 2019-03-01 12:57:59,965 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [subscription_id, collection_id, eperson_id] > 2019-03-01 12:57:59,965 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.tasklistitem > 2019-03-01 12:57:59,965 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [workflow_id, eperson_id, tasklist_id] > 2019-03-01 12:57:59,980 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.versionhistory > 2019-03-01 12:57:59,980 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [versionhistory_id] > 2019-03-01 12:57:59,980 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.versionitem > 2019-03-01 12:57:59,980 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [version_summary, eperson_id, item_id, version_date, > version_number, versionitem_id, versionhistory_id] > 2019-03-01 12:57:59,996 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.webapp > 2019-03-01 12:57:59,996 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [webapp_id, appname, started, url, isui] > 2019-03-01 12:57:59,996 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.workflowitem > 2019-03-01 12:57:59,996 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, owner, workflow_id, published_before, > item_id, multiple_titles, multiple_files, state] > 2019-03-01 12:58:00,012 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.workspaceitem > 2019-03-01 12:58:00,012 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, stage_reached, page_reached, > published_before, item_id, multiple_titles, multiple_files, > workspace_item_id] > 2019-03-01 12:58:00,215 INFO > org.dspace.storage.rdbms.DatabaseRegistryUpdater @ Updating Bitstream > Format Registry based on /dspace\config\registries\bitstream-formats.xml > 2019-03-01 12:58:00,840 WARN > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ SQL Error: 0, SQLState: > 42601 > 2019-03-01 12:58:00,840 ERROR > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ ERROR: syntax error at > or near "." > Position: 244 > 2019-03-01 12:58:00,840 ERROR > org.dspace.storage.rdbms.DatabaseRegistryUpdater @ Error attempting to > update Bitstream Format and/or Metadata Registries > org.hibernate.exception.SQLGrammarException: could not extract ResultSet > at > org.hibernate.exception.internal.SQLStateConversionDelegate.convert(SQLStateConversionDelegate.java:123) > at > org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:49) > at > org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:124) > at > org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:109) > at > org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:88) > at org.hibernate.loader.Loader.getResultSet(Loader.java:2062) > at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1859) > at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1838) > at org.hibernate.loader.Loader.doQuery(Loader.java:906) > at > org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:348) > at org.hibernate.loader.Loader.doList(Loader.java:2550) > at org.hibernate.loader.Loader.doList(Loader.java:2536) > at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2366) > at org.hibernate.loader.Loader.list(Loader.java:2361) > at > org.hibernate.loader.criteria.CriteriaLoader.list(CriteriaLoader.java:124) > at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1623) > at org.hibernate.internal.CriteriaImpl.list(CriteriaImpl.java:374) > at > org.dspace.core.AbstractHibernateDAO.list(AbstractHibernateDAO.java:158) > at > org.dspace.core.AbstractHibernateDAO.singleResult(AbstractHibernateDAO.java:212) > at > org.dspace.content.dao.impl.BitstreamFormatDAOImpl.findByMIMEType(BitstreamFormatDAOImpl.java:63) > at > org.dspace.content.BitstreamFormatServiceImpl.findByMIMEType(BitstreamFormatServiceImpl.java:95) > at > org.dspace.administer.RegistryLoader.loadFormat(RegistryLoader.java:184) > at > org.dspace.administer.RegistryLoader.loadBitstreamFormats(RegistryLoader.java:146) > at > org.dspace.storage.rdbms.DatabaseRegistryUpdater.updateRegistries(DatabaseRegistryUpdater.java:68) > at > org.dspace.storage.rdbms.DatabaseRegistryUpdater.afterMigrate(DatabaseRegistryUpdater.java:121) > at > org.flywaydb.core.internal.command.DbMigrate$3.doInTransaction(DbMigrate.java:250) > at > org.flywaydb.core.internal.util.jdbc.TransactionTemplate.execute(TransactionTemplate.java:72) > at > org.flywaydb.core.internal.command.DbMigrate.migrate(DbMigrate.java:246) > at org.flywaydb.core.Flyway$1.execute(Flyway.java:959) > at org.flywaydb.core.Flyway$1.execute(Flyway.java:917) > at org.flywaydb.core.Flyway.execute(Flyway.java:1373) > at org.flywaydb.core.Flyway.migrate(Flyway.java:917) > at > org.dspace.storage.rdbms.DatabaseUtils.updateDatabase(DatabaseUtils.java:662) > at > org.dspace.storage.rdbms.DatabaseUtils.updateDatabase(DatabaseUtils.java:576) > at > org.dspace.storage.rdbms.DatabaseUtils.updateDatabase(DatabaseUtils.java:552) > at org.dspace.core.Context.<clinit>(Context.java:103) > at > org.dspace.app.util.AbstractDSpaceWebapp.register(AbstractDSpaceWebapp.java:74) > at > org.dspace.app.util.DSpaceWebappListener.contextInitialized(DSpaceWebappListener.java:31) > at > org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4792) > at > org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5256) > at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) > at > org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:754) > at > org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:730) > at > org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734) > at > org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1140) > at > org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1875) > at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) > at java.util.concurrent.FutureTask.run(Unknown Source) > at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) > at java.lang.Thread.run(Unknown Source) > Caused by: org.postgresql.util.PSQLException: ERROR: syntax error at or > near "." > Position: 244 > at > org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2422) > at > org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2167) > at > org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:306) > at > org.postgresql.jdbc.PgStatement.executeInternal(PgStatement.java:441) > at org.postgresql.jdbc.PgStatement.execute(PgStatement.java:365) > at > org.postgresql.jdbc.PgPreparedStatement.executeWithFlags(PgPreparedStatement.java:155) > at > org.postgresql.jdbc.PgPreparedStatement.executeQuery(PgPreparedStatement.java:118) > at > org.apache.commons.dbcp2.DelegatingPreparedStatement.executeQuery(DelegatingPreparedStatement.java:83) > at > org.apache.commons.dbcp2.DelegatingPreparedStatement.executeQuery(DelegatingPreparedStatement.java:83) > at > org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.extract(ResultSetReturnImpl.java:79) > ... 46 more > 2019-03-01 12:58:06,262 INFO > net.sf.ehcache.store.compound.factories.DiskPersistentStorageFactory @ > Loading old format index file. > 2019-03-01 12:58:07,668 INFO org.dspace.statistics.SolrLoggerServiceImpl > @ solr-statistics.spidersfile:null > 2019-03-01 12:58:07,668 INFO org.dspace.statistics.SolrLoggerServiceImpl > @ solr-statistics.server:http://localhost:80/solr/statistics > 2019-03-01 12:58:07,684 INFO org.dspace.statistics.SolrLoggerServiceImpl > @ usage-statistics.dbfile:/dspace/config/GeoLite2-City.mmdb > 2019-03-01 12:58:08,701 INFO org.dspace.statistics.SolrLoggerServiceImpl > @ useProxies=false > 2019-03-01 13:00:45,429 INFO > org.dspace.importer.external.service.ImportService @ Loading 1 import > sources. > 2019-03-01 13:00:50,601 INFO org.dspace.core.LoggerServiceImpl @ Using > dspace provided log configuration (log.init.config) > 2019-03-01 13:00:50,601 INFO org.dspace.core.LoggerServiceImpl @ Loading: > C:\dspace/config/log4j.properties > 2019-03-01 13:00:54,273 INFO org.dspace.storage.rdbms.DatabaseUtils @ > Loading Flyway DB migrations from: filesystem:C:\dspace/etc/postgres, > classpath:org.dspace.storage.rdbms.sqlmigration.postgres, > classpath:org.dspace.storage.rdbms.migration > 2019-03-01 13:00:54,351 INFO > org.flywaydb.core.internal.util.VersionPrinter @ Flyway 4.0.3 by Boxfuse > 2019-03-01 13:00:54,398 INFO > org.flywaydb.core.internal.dbsupport.DbSupportFactory @ Database: > jdbc:postgresql://localhost:5432/dspace (PostgreSQL 9.4) > 2019-03-01 13:07:06,317 INFO > org.dspace.importer.external.service.ImportService @ Loading 1 import > sources. > 2019-03-01 13:07:10,161 INFO org.dspace.core.LoggerServiceImpl @ Using > dspace provided log configuration (log.init.config) > 2019-03-01 13:07:10,161 INFO org.dspace.core.LoggerServiceImpl @ Loading: > C:\dspace/config/log4j.properties > 2019-03-01 13:07:13,848 INFO org.dspace.storage.rdbms.DatabaseUtils @ > Loading Flyway DB migrations from: filesystem:C:\dspace/etc/postgres, > classpath:org.dspace.storage.rdbms.sqlmigration.postgres, > classpath:org.dspace.storage.rdbms.migration > 2019-03-01 13:07:13,926 INFO > org.flywaydb.core.internal.util.VersionPrinter @ Flyway 4.0.3 by Boxfuse > 2019-03-01 13:07:13,973 INFO > org.flywaydb.core.internal.dbsupport.DbSupportFactory @ Database: > jdbc:postgresql://localhost:5432/dspace (PostgreSQL 9.4) > 2019-03-01 13:09:06,272 INFO org.dspace.storage.rdbms.DatabaseUtils @ > Loading Flyway DB migrations from: filesystem:/dspace/etc/postgres, > classpath:org.dspace.storage.rdbms.sqlmigration.postgres, > classpath:org.dspace.storage.rdbms.migration > 2019-03-01 13:09:06,303 INFO > org.flywaydb.core.internal.util.VersionPrinter @ Flyway 4.0.3 by Boxfuse > 2019-03-01 13:09:06,365 INFO > org.flywaydb.core.internal.dbsupport.DbSupportFactory @ Database: > jdbc:postgresql://localhost:5432/dspace (PostgreSQL 9.4) > 2019-03-01 13:09:06,928 INFO org.dspace.storage.rdbms.DatabaseUtils @ > DSpace database schema is up to date > 2019-03-01 13:09:06,959 INFO org.dspace.event.EventServiceImpl @ > EventService dispatcher pool initialized > 2019-03-01 13:09:07,162 INFO org.hibernate.annotations.common.Version @ > HCANN000001: Hibernate Commons Annotations {4.0.2.Final} > 2019-03-01 13:09:07,209 INFO org.hibernate.Version @ HHH000412: Hibernate > Core {4.2.21.Final} > 2019-03-01 13:09:07,209 INFO org.hibernate.cfg.Environment @ HHH000206: > hibernate.properties not found > 2019-03-01 13:09:07,209 INFO org.hibernate.cfg.Environment @ HHH000021: > Bytecode provider name : javassist > 2019-03-01 13:09:07,240 INFO org.hibernate.cfg.Configuration @ HHH000044: > Configuring from URL: file:/dspace/config/hibernate.cfg.xml > 2019-03-01 13:09:07,412 INFO org.hibernate.cfg.Configuration @ HHH000041: > Configured SessionFactory: null > 2019-03-01 13:09:07,819 INFO org.hibernate.dialect.Dialect @ HHH000400: > Using dialect: > org.dspace.storage.rdbms.hibernate.postgres.DSpacePostgreSQL82Dialect > 2019-03-01 13:09:07,850 INFO > org.hibernate.engine.jdbc.internal.LobCreatorBuilder @ HHH000424: Disabling > contextual LOB creation as createClob() method threw error : > java.lang.reflect.InvocationTargetException > 2019-03-01 13:09:07,881 INFO org.hibernate.type.BasicTypeRegistry @ > HHH000270: Type registration [pg-uuid] overrides previous : > org.hibernate.type.PostgresUUIDType@80acd6 > 2019-03-01 13:09:07,881 INFO org.hibernate.type.BasicTypeRegistry @ > HHH000270: Type registration [java.util.UUID] overrides previous : > org.hibernate.type.UUIDBinaryType@7c01edc3 > 2019-03-01 13:09:08,943 INFO > org.hibernate.engine.transaction.internal.TransactionFactoryInitiator @ > HHH000399: Using default transaction strategy (direct JDBC transactions) > 2019-03-01 13:09:08,943 INFO > org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory @ HHH000397: Using > ASTQueryTranslatorFactory > 2019-03-01 13:09:09,037 WARN net.sf.ehcache.CacheManager @ Creating a new > instance of CacheManager using the diskStorePath "C:\Program > Files\apache-tomcat-8.5.31\temp" which is already used by an existing > CacheManager. > The source of the configuration was > net.sf.ehcache.config.generator.ConfigurationSource$URLConfigurationSource@c0a45f5. > The diskStore path for this CacheManager will be set to C:\Program > Files\apache-tomcat-8.5.31\temp\ehcache_auto_created_1551463749037. > To avoid this warning consider using the CacheManager factory methods to > create a singleton CacheManager or specifying a separate ehcache > configuration (ehcache.xml) for each CacheManager instance. > 2019-03-01 13:09:09,084 INFO > org.hibernate.cache.spi.UpdateTimestampsCache @ HHH000250: Starting update > timestamps cache at region: org.hibernate.cache.spi.UpdateTimestampsCache > 2019-03-01 13:09:09,084 INFO > org.hibernate.cache.internal.StandardQueryCache @ HHH000248: Starting query > cache at region: org.hibernate.cache.internal.StandardQueryCache > 2019-03-01 13:09:10,131 INFO org.hibernate.tool.hbm2ddl.SchemaValidator @ > HHH000229: Running schema validator > 2019-03-01 13:09:10,131 INFO org.hibernate.tool.hbm2ddl.SchemaValidator @ > HHH000102: Fetching database metadata > 2019-03-01 13:09:10,162 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.doi > 2019-03-01 13:09:10,162 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [dspace_object, resource_type_id, resource_id, doi_id, > doi, status] > 2019-03-01 13:09:10,162 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.bitstream > 2019-03-01 13:09:10,162 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [deleted, size_bytes, internal_id, checksum_algorithm, > bitstream_id, checksum, store_number, sequence_id, bitstream_format_id, > uuid] > 2019-03-01 13:09:10,178 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.bitstreamformatregistry > 2019-03-01 13:09:10,178 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [short_description, internal, description, > bitstream_format_id, mimetype, support_level] > 2019-03-01 13:09:10,178 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.bundle > 2019-03-01 13:09:10,178 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [primary_bitstream_id, bundle_id, uuid] > 2019-03-01 13:09:10,178 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.bundle2bitstream > 2019-03-01 13:09:10,178 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [bitstream_order, bitstream_order_legacy, bundle_id, > bitstream_id] > 2019-03-01 13:09:10,193 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.checksum_history > 2019-03-01 13:09:10,193 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [result, process_start_date, process_end_date, > bitstream_id, check_id, checksum_calculated, checksum_expected] > 2019-03-01 13:09:10,209 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.checksum_results > 2019-03-01 13:09:10,209 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [result_description, result_code] > 2019-03-01 13:09:10,209 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.collection > 2019-03-01 13:09:10,209 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, submitter, logo_bitstream_id, > workflow_step_3, admin, workflow_step_1, workflow_step_2, template_item_id, > uuid] > 2019-03-01 13:09:10,209 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.collection2item > 2019-03-01 13:09:10,209 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, item_id] > 2019-03-01 13:09:10,225 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.community > 2019-03-01 13:09:10,225 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [logo_bitstream_id, community_id, admin, uuid] > 2019-03-01 13:09:10,225 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.community2collection > 2019-03-01 13:09:10,225 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, community_id] > 2019-03-01 13:09:10,225 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.community2community > 2019-03-01 13:09:10,225 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [parent_comm_id, child_comm_id] > 2019-03-01 13:09:10,240 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.dspaceobject > 2019-03-01 13:09:10,240 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [uuid] > 2019-03-01 13:09:10,240 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.eperson > 2019-03-01 13:09:10,240 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [sub_frequency, password, can_log_in, digest_algorithm, > salt, eperson_id, netid, last_active, self_registered, uuid, email, > require_certificate] > 2019-03-01 13:09:10,240 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.epersongroup > 2019-03-01 13:09:10,240 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [permanent, name, eperson_group_id, uuid] > 2019-03-01 13:09:10,256 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.epersongroup2eperson > 2019-03-01 13:09:10,256 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [eperson_id, eperson_group_id] > 2019-03-01 13:09:10,256 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.epersongroup2workspaceitem > 2019-03-01 13:09:10,256 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [eperson_group_id, workspace_item_id] > 2019-03-01 13:09:10,256 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.fileextension > 2019-03-01 13:09:10,256 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [file_extension_id, extension, bitstream_format_id] > 2019-03-01 13:09:10,272 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.group2group > 2019-03-01 13:09:10,272 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [child_id, parent_id] > 2019-03-01 13:09:10,272 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.group2groupcache > 2019-03-01 13:09:10,272 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [child_id, parent_id] > 2019-03-01 13:09:10,287 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.handle > 2019-03-01 13:09:10,287 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [handle_id, resource_legacy_id, resource_type_id, > resource_id, handle] > 2019-03-01 13:09:10,287 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.harvested_collection > 2019-03-01 13:09:10,287 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, oai_source, metadata_config_id, > last_harvested, oai_set_id, harvest_type, harvest_status, id, > harvest_message, harvest_start_time] > 2019-03-01 13:09:10,287 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.harvested_item > 2019-03-01 13:09:10,287 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [oai_id, last_harvested, item_id, id] > 2019-03-01 13:09:10,334 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.item > 2019-03-01 13:09:10,334 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [item_id, discoverable, in_archive, withdrawn, > submitter_id, last_modified, uuid, owning_collection] > 2019-03-01 13:09:10,334 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.item2bundle > 2019-03-01 13:09:10,334 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [item_id, bundle_id] > 2019-03-01 13:09:10,334 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.metadatafieldregistry > 2019-03-01 13:09:10,334 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [scope_note, metadata_schema_id, qualifier, > metadata_field_id, element] > 2019-03-01 13:09:10,350 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.metadataschemaregistry > 2019-03-01 13:09:10,350 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [metadata_schema_id, namespace, short_id] > 2019-03-01 13:09:10,350 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.metadatavalue > 2019-03-01 13:09:10,350 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [text_value, dspace_object_id, text_lang, authority, > confidence, metadata_value_id, place, metadata_field_id] > 2019-03-01 13:09:10,350 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.most_recent_checksum > 2019-03-01 13:09:10,350 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [last_process_end_date, result, expected_checksum, > matched_prev_checksum, to_be_processed, current_checksum, > checksum_algorithm, bitstream_id, last_process_start_date] > 2019-03-01 13:09:10,365 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.registrationdata > 2019-03-01 13:09:10,365 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [registrationdata_id, expires, email, token] > 2019-03-01 13:09:10,365 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.requestitem > 2019-03-01 13:09:10,365 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [request_name, decision_date, expires, item_id, > request_message, request_email, request_date, bitstream_id, allfiles, > accept_request, requestitem_id, token] > 2019-03-01 13:09:10,412 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.resourcepolicy > 2019-03-01 13:09:10,412 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [end_date, dspace_object, rptype, resource_type_id, > policy_id, eperson_id, action_id, epersongroup_id, rpdescription, > resource_id, start_date, rpname] > 2019-03-01 13:09:10,412 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.site > 2019-03-01 13:09:10,412 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [uuid] > 2019-03-01 13:09:10,443 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.subscription > 2019-03-01 13:09:10,443 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [subscription_id, collection_id, eperson_id] > 2019-03-01 13:09:10,443 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.tasklistitem > 2019-03-01 13:09:10,443 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [workflow_id, eperson_id, tasklist_id] > 2019-03-01 13:09:10,443 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.versionhistory > 2019-03-01 13:09:10,443 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [versionhistory_id] > 2019-03-01 13:09:10,459 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.versionitem > 2019-03-01 13:09:10,459 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [version_summary, eperson_id, item_id, version_date, > version_number, versionitem_id, versionhistory_id] > 2019-03-01 13:09:10,459 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.webapp > 2019-03-01 13:09:10,459 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [webapp_id, appname, started, url, isui] > 2019-03-01 13:09:10,459 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.workflowitem > 2019-03-01 13:09:10,459 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, owner, workflow_id, published_before, > item_id, multiple_titles, multiple_files, state] > 2019-03-01 13:09:10,475 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000261: Table found: public.workspaceitem > 2019-03-01 13:09:10,475 INFO org.hibernate.tool.hbm2ddl.TableMetadata @ > HHH000037: Columns: [collection_id, stage_reached, page_reached, > published_before, item_id, multiple_titles, multiple_files, > workspace_item_id] > 2019-03-01 13:09:10,756 INFO > org.hibernate.engine.jdbc.batch.internal.AbstractBatchImpl @ HHH000010: On > release of batch it still contained JDBC statements > 2019-03-01 13:09:10,756 WARN > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ SQL Error: 0, SQLState: > 42601 > 2019-03-01 13:09:10,756 ERROR > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ Batch entry 0 insert > into .webapp (appname, isui, started, url, webapp_id) values (NULL, NULL, > NULL, NULL, 396) was aborted: ERROR: syntax error at or near "." > Position: 13 Call getNextException to see other errors in the batch. > 2019-03-01 13:09:10,756 WARN > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ SQL Error: 0, SQLState: > 42601 > 2019-03-01 13:09:10,756 ERROR > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ ERROR: syntax error at > or near "." > Position: 13 > 2019-03-01 13:09:10,787 ERROR > org.hibernate.engine.jdbc.batch.internal.BatchingBatch @ HHH000315: > Exception executing batch [could not execute batch] > 2019-03-01 13:09:15,365 INFO org.dspace.statistics.SolrLoggerServiceImpl > @ solr-statistics.spidersfile:null > 2019-03-01 13:09:15,365 INFO org.dspace.statistics.SolrLoggerServiceImpl > @ solr-statistics.server:http://localhost:80/solr/statistics > 2019-03-01 13:09:15,365 INFO org.dspace.statistics.SolrLoggerServiceImpl > @ usage-statistics.dbfile:/dspace/config/GeoLite2-City.mmdb > 2019-03-01 13:09:16,256 INFO org.dspace.statistics.SolrLoggerServiceImpl > @ useProxies=false > 2019-03-01 13:09:16,849 INFO > org.hibernate.engine.jdbc.batch.internal.AbstractBatchImpl @ HHH000010: On > release of batch it still contained JDBC statements > 2019-03-01 13:09:16,849 WARN > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ SQL Error: 0, SQLState: > 42601 > 2019-03-01 13:09:16,849 ERROR > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ Batch entry 0 delete > from .webapp where webapp_id=396 was aborted: ERROR: syntax error at or > near "." > Position: 13 Call getNextException to see other errors in the batch. > 2019-03-01 13:09:16,849 WARN > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ SQL Error: 0, SQLState: > 42601 > 2019-03-01 13:09:16,849 ERROR > org.hibernate.engine.jdbc.spi.SqlExceptionHelper @ ERROR: syntax error at > or near "." > Position: 13 > 2019-03-01 13:09:16,849 ERROR > org.hibernate.engine.jdbc.batch.internal.BatchingBatch @ HHH000315: > Exception executing batch [could not execute batch] > 2019-03-01 13:34:35,357 INFO > org.dspace.importer.external.service.ImportService @ Loading 1 import > sources. > 2019-03-01 13:34:39,060 INFO org.dspace.core.LoggerServiceImpl @ Using > dspace provided log configuration (log.init.config) > 2019-03-01 13:34:39,060 INFO org.dspace.core.LoggerServiceImpl @ Loading: > C:\dspace/config/log4j.properties > 2019-03-01 13:34:42,638 INFO org.dspace.storage.rdbms.DatabaseUtils @ > Loading Flyway DB migrations from: filesystem:C:\dspace/etc/postgres, > classpath:org.dspace.storage.rdbms.sqlmigration.postgres, > classpath:org.dspace.storage.rdbms.migration > 2019-03-01 13:34:42,716 INFO > org.flywaydb.core.internal.util.VersionPrinter @ Flyway 4.0.3 by Boxfuse > 2019-03-01 13:34:42,763 INFO > org.flywaydb.core.internal.dbsupport.DbSupportFactory @ Database: > jdbc:postgresql://localhost:5432/dspace (PostgreSQL 9.4) > > > > On Thursday, February 28, 2019 at 4:51:24 PM UTC-5, Tim Donohue wrote: >> >> Hi David, >> >> You are correct, this is related to the bug report in DS-2824. The >> problem is that you seem to have somehow created two Groups of the *same >> name*. In your situation, the errors is saying you have two groups named >> "COLLECTION_29_DEFAULT_READ". Group Names are supposed to be unique (and >> uniqueness is now guaranteed at the database level). This is a very rare >> scenario, as DSpace should have never allowed two groups of the same name >> to be created. But, somehow this seems to have occurred at least a few >> times (on your site, and on the site that reported bug DS-2824). >> >> In any case, the fix should be to look for two groups named >> COLLECTION_29_DEFAULT_READ (this group is named oddly because it is the >> group that is providing DEFAULT_READ permissions to your Collection with >> ID=29). Figure out which one is the correct one, and delete the other one. >> If you rerun the migration it should now succeed (again assuming there are >> no other duplicative groups -- but there hopefully should not be). >> >> Good luck, >> >> Tim >> >> >> >> On Thu, Feb 28, 2019 at 2:29 PM David Baker <[email protected]> wrote: >> >>> Anybody have any ideas why I'm running into this? >>> I found this bug report https://jira.duraspace.org/browse/DS-2824 but >>> don't understand how the issue was resolved in that case. >>> Thanks. >>> >>> >>> On Thursday, February 28, 2019 at 3:27:27 PM UTC-5, David Baker wrote: >>>> >>>> I'm upgrading DSpace on Windows Server 2012 from version 5.3 to 6.3 >>>> >>>> Here's the output of bin\dspace database info: >>>> >>>> Using DSpace installation in: c:\dspace >>>> >>>> Database Type: postgres >>>> Database URL: jdbc:postgresql://localhost:5432/dspace >>>> Database Schema: public >>>> Database Username: dspace >>>> Database Software: PostgreSQL version 9.4.17 >>>> Database Driver: PostgreSQL JDBC Driver version 42.2.1 >>>> PostgreSQL 'pgcrypto' extension installed/up-to-date? true (version=1.1) >>>> >>>> >>>> +----------------+-----------------------------------------------------+---------------------+---------+ >>>> | Version | Description >>>> | Installed on | State | >>>> >>>> +----------------+-----------------------------------------------------+---------------------+---------+ >>>> | 1 | << Flyway Init >> >>>> | 2015-06-09 12:16:21 | Baselin | >>>> | 1.1 | Initial DSpace 1.1 database schema >>>> | 2015-06-09 12:16:22 | Success | >>>> | 1.2 | Upgrade to DSpace 1.2 schema >>>> | 2015-06-09 12:16:22 | Success | >>>> | 1.3 | Upgrade to DSpace 1.3 schema >>>> | 2015-06-09 12:16:22 | Success | >>>> | 1.3.9 | Drop constraint for DSpace 1 4 schema >>>> | 2015-06-09 12:16:23 | Success | >>>> | 1.4 | Upgrade to DSpace 1.4 schema >>>> | 2015-06-09 12:16:23 | Success | >>>> | 1.5 | Upgrade to DSpace 1.5 schema >>>> | 2015-06-09 12:16:23 | Success | >>>> | 1.5.9 | Drop constraint for DSpace 1 6 schema >>>> | 2015-06-09 12:16:24 | Success | >>>> | 1.6 | Upgrade to DSpace 1.6 schema >>>> | 2015-06-09 12:16:24 | Success | >>>> | 1.7 | Upgrade to DSpace 1.7 schema >>>> | 2015-06-09 12:16:24 | Success | >>>> | 1.8 | Upgrade to DSpace 1.8 schema >>>> | 2015-06-09 12:16:24 | Success | >>>> | 3.0 | Upgrade to DSpace 3.x schema >>>> | 2015-06-09 12:16:24 | Success | >>>> | 4.0 | Upgrade to DSpace 4.x schema >>>> | 2015-06-09 12:16:24 | Success | >>>> | 4.9.2015.10.26 | DS-2818 registry update >>>> | | Ignored | >>>> | 5.0.2014.08.08 | DS-1945 Helpdesk Request a Copy >>>> | 2015-06-09 12:16:24 | Success | >>>> | 5.0.2014.09.25 | DS 1582 Metadata For All Objects drop constraint >>>> | 2015-06-09 12:16:24 | Success | >>>> | 5.0.2014.09.26 | DS-1582 Metadata For All Objects >>>> | 2015-06-09 12:16:24 | Success | >>>> | 5.6.2016.08.23 | DS-3097 >>>> | 2019-02-28 14:52:18 | Success | >>>> | 5.7.2017.04.11 | DS-3563 Index metadatavalue resource type id column >>>> | 2019-02-28 14:52:20 | Success | >>>> | 5.7.2017.05.05 | DS 3431 Add Policies for BasicWorkflow >>>> | 2019-02-28 14:52:21 | Success | >>>> | 6.0.2015.03.06 | DS 2701 Dso Uuid Migration >>>> | 2019-02-28 14:52:22 | Success | >>>> | 6.0.2015.03.07 | DS-2701 Hibernate migration >>>> | 2019-02-28 14:52:23 | Success | >>>> | 6.0.2015.08.31 | DS 2701 Hibernate Workflow Migration >>>> | 2019-02-28 14:54:57 | Success | >>>> | 6.0.2016.01.03 | DS-3024 >>>> | 2019-02-28 14:54:57 | Success | >>>> | 6.0.2016.01.26 | DS 2188 Remove DBMS Browse Tables >>>> | 2019-02-28 14:54:58 | Success | >>>> | 6.0.2016.02.25 | DS-3004-slow-searching-as-admin >>>> | | Pending | >>>> | 6.0.2016.04.01 | DS-1955 Increase embargo reason >>>> | | Pending | >>>> | 6.0.2016.04.04 | DS-3086-OAI-Performance-fix >>>> | | Pending | >>>> | 6.0.2016.04.14 | DS-3125-fix-bundle-bitstream-delete-rights >>>> | | Pending | >>>> | 6.0.2016.05.10 | DS-3168-fix-requestitem item id column >>>> | | Pending | >>>> | 6.0.2016.07.21 | DS-2775 >>>> | | Pending | >>>> | 6.0.2016.07.26 | DS-3277 fix handle assignment >>>> | | Pending | >>>> | 6.0.2016.08.23 | DS-3097 >>>> | | Pending | >>>> | 6.1.2017.01.03 | DS 3431 Add Policies for BasicWorkflow >>>> | | Pending | >>>> >>>> +----------------+-----------------------------------------------------+---------------------+---------+ >>>> >>>> >>>> Then there's this in my dspace.log file: >>>> >>>> >>>> 2019-02-28 14:54:58,834 INFO >>>> org.flywaydb.core.internal.command.DbMigrate @ Migrating schema "public" to >>>> version 6.0.2016.02.25 - DS-3004-slow-searching-as-admin >>>> 2019-02-28 14:54:58,928 INFO >>>> org.flywaydb.core.internal.dbsupport.JdbcTemplate @ DB: column "name" of >>>> relation "epersongroup" does not exist, skipping >>>> 2019-02-28 14:54:59,537 ERROR >>>> org.flywaydb.core.internal.command.DbMigrate @ Migration of schema "public" >>>> to version 6.0.2016.02.25 - DS-3004-slow-searching-as-admin failed! Changes >>>> successfully rolled back. >>>> 2019-02-28 14:54:59,537 FATAL org.dspace.core.Context @ Cannot >>>> initialize database via Flyway! >>>> java.sql.SQLException: Flyway migration error occurred >>>> at >>>> org.dspace.storage.rdbms.DatabaseUtils.updateDatabase(DatabaseUtils.java:673) >>>> at >>>> org.dspace.storage.rdbms.DatabaseUtils.updateDatabase(DatabaseUtils.java:576) >>>> at >>>> org.dspace.storage.rdbms.DatabaseUtils.updateDatabase(DatabaseUtils.java:552) >>>> at org.dspace.core.Context.<clinit>(Context.java:103) >>>> at >>>> org.dspace.app.util.AbstractDSpaceWebapp.register(AbstractDSpaceWebapp.java:74) >>>> at >>>> org.dspace.app.util.DSpaceWebappListener.contextInitialized(DSpaceWebappListener.java:31) >>>> at >>>> org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4792) >>>> at >>>> org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5256) >>>> at >>>> org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) >>>> at >>>> org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:754) >>>> at >>>> org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:730) >>>> at >>>> org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734) >>>> at >>>> org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1140) >>>> at >>>> org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1875) >>>> at java.util.concurrent.Executors$RunnableAdapter.call(Unknown >>>> Source) >>>> at java.util.concurrent.FutureTask.run(Unknown Source) >>>> at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) >>>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown >>>> Source) >>>> at java.lang.Thread.run(Unknown Source) >>>> Caused by: >>>> org.flywaydb.core.internal.dbsupport.FlywaySqlScriptException: >>>> Migration V6.0_2016.02.25__DS-3004-slow-searching-as-admin.sql failed >>>> --------------------------------------------------------------------- >>>> SQL State : 23505 >>>> Error Code : 0 >>>> Message : ERROR: duplicate key value violates unique constraint >>>> "epersongroup_unique_idx_name" >>>> Detail: Key (name)=(COLLECTION_29_DEFAULT_READ) already exists. >>>> Location : >>>> org/dspace/storage/rdbms/sqlmigration/postgres/V6.0_2016.02.25__DS-3004-slow-searching-as-admin.sql >>>> (C:\Program Files\apache-tomcat-8.5.31\file:\C:\Program >>>> Files\apache-tomcat-8.5.31\webapps\ROOT\WEB-INF\lib\dspace-api-6.3.jar!\org\dspace\storage\rdbms\sqlmigration\postgres\V6.0_2016.02.25__DS-3004-slow-searching-as-admin.sql) >>>> Line : 26 >>>> Statement : UPDATE epersongroup >>>> SET name = >>>> (SELECT text_value >>>> FROM metadatavalue v >>>> JOIN metadatafieldregistry field on v.metadata_field_id = >>>> field.metadata_field_id >>>> JOIN metadataschemaregistry s ON field.metadata_schema_id = >>>> s.metadata_schema_id >>>> WHERE s.short_id = 'dc' AND element = 'title' AND qualifier IS NULL >>>> AND v.dspace_object_id = epersongroup.uuid LIMIT 1) >>>> >>>> at >>>> org.flywaydb.core.internal.dbsupport.SqlScript.execute(SqlScript.java:117) >>>> at >>>> org.flywaydb.core.internal.resolver.sql.SqlMigrationExecutor.execute(SqlMigrationExecutor.java:71) >>>> at >>>> org.flywaydb.core.internal.command.DbMigrate.doMigrate(DbMigrate.java:352) >>>> at >>>> org.flywaydb.core.internal.command.DbMigrate.access$1100(DbMigrate.java:47) >>>> at >>>> org.flywaydb.core.internal.command.DbMigrate$4.doInTransaction(DbMigrate.java:308) >>>> at >>>> org.flywaydb.core.internal.util.jdbc.TransactionTemplate.execute(TransactionTemplate.java:72) >>>> at >>>> org.flywaydb.core.internal.command.DbMigrate.applyMigration(DbMigrate.java:305) >>>> at >>>> org.flywaydb.core.internal.command.DbMigrate.access$1000(DbMigrate.java:47) >>>> at >>>> org.flywaydb.core.internal.command.DbMigrate$2.doInTransaction(DbMigrate.java:230) >>>> at >>>> org.flywaydb.core.internal.command.DbMigrate$2.doInTransaction(DbMigrate.java:173) >>>> at >>>> org.flywaydb.core.internal.util.jdbc.TransactionTemplate.execute(TransactionTemplate.java:72) >>>> at >>>> org.flywaydb.core.internal.command.DbMigrate.migrate(DbMigrate.java:173) >>>> at org.flywaydb.core.Flyway$1.execute(Flyway.java:959) >>>> at org.flywaydb.core.Flyway$1.execute(Flyway.java:917) >>>> at org.flywaydb.core.Flyway.execute(Flyway.java:1373) >>>> at org.flywaydb.core.Flyway.migrate(Flyway.java:917) >>>> at >>>> org.dspace.storage.rdbms.DatabaseUtils.updateDatabase(DatabaseUtils.java:662) >>>> ... 18 more >>>> Caused by: org.postgresql.util.PSQLException: ERROR: duplicate key >>>> value violates unique constraint "epersongroup_unique_idx_name" >>>> Detail: Key (name)=(COLLECTION_29_DEFAULT_READ) already exists. >>>> at >>>> org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2422) >>>> at >>>> org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2167) >>>> at >>>> org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:306) >>>> at >>>> org.postgresql.jdbc.PgStatement.executeInternal(PgStatement.java:441) >>>> at org.postgresql.jdbc.PgStatement.execute(PgStatement.java:365) >>>> at >>>> org.postgresql.jdbc.PgStatement.executeWithFlags(PgStatement.java:307) >>>> at >>>> org.postgresql.jdbc.PgStatement.executeCachedSql(PgStatement.java:293) >>>> at >>>> org.postgresql.jdbc.PgStatement.executeWithFlags(PgStatement.java:270) >>>> at org.postgresql.jdbc.PgStatement.execute(PgStatement.java:266) >>>> at >>>> org.apache.commons.dbcp2.DelegatingStatement.execute(DelegatingStatement.java:291) >>>> at >>>> org.apache.commons.dbcp2.DelegatingStatement.execute(DelegatingStatement.java:291) >>>> at >>>> org.flywaydb.core.internal.dbsupport.JdbcTemplate.executeStatement(JdbcTemplate.java:238) >>>> at >>>> org.flywaydb.core.internal.dbsupport.SqlScript.execute(SqlScript.java:114) >>>> >>>> >>> -- >>> All messages to this mailing list should adhere to the DuraSpace Code of >>> Conduct: https://duraspace.org/about/policies/code-of-conduct/ >>> --- >>> You received this message because you are subscribed to the Google >>> Groups "DSpace Technical Support" group. >>> To unsubscribe from this group and stop receiving emails from it, send >>> an email to [email protected]. >>> To post to this group, send email to [email protected]. >>> Visit this group at https://groups.google.com/group/dspace-tech. >>> For more options, visit https://groups.google.com/d/optout. >>> >> >> >> -- >> >> Tim Donohue >> Technical Lead for DSpace & DSpaceDirect >> DuraSpace.org | DSpace.org | DSpaceDirect.org >> >> -- > All messages to this mailing list should adhere to the DuraSpace Code of > Conduct: https://duraspace.org/about/policies/code-of-conduct/ > --- > You received this message because you are subscribed to the Google Groups > "DSpace Technical Support" group. > To unsubscribe from this group and stop receiving emails from it, send an > email to [email protected]. > To post to this group, send email to [email protected]. > Visit this group at https://groups.google.com/group/dspace-tech. > For more options, visit https://groups.google.com/d/optout. > -- Tim Donohue Technical Lead for DSpace & DSpaceDirect DuraSpace.org | DSpace.org | DSpaceDirect.org -- All messages to this mailing list should adhere to the DuraSpace Code of Conduct: https://duraspace.org/about/policies/code-of-conduct/ --- You received this message because you are subscribed to the Google Groups "DSpace Technical Support" group. To unsubscribe from this group and stop receiving emails from it, send an email to [email protected]. To post to this group, send email to [email protected]. Visit this group at https://groups.google.com/group/dspace-tech. For more options, visit https://groups.google.com/d/optout.
