Jul 21 19:43:29 OB-VM systemd[1]: openboxes.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:43:29 OB-VM systemd[1]: openboxes.service: Failed with result 'exit-code'. Jul 21 19:43:39 OB-VM systemd[1]: openboxes.service: Service hold-off time over, scheduling restart. Jul 21 19:43:39 OB-VM systemd[1]: openboxes.service: Scheduled restart job, restart counter is at 19. Jul 21 19:43:39 OB-VM systemd[1]: Stopped OpenBoxes app. Jul 21 19:43:39 OB-VM systemd[1]: Started OpenBoxes app. Jul 21 19:43:39 OB-VM java[30566]: Picked up JAVA_TOOL_OPTIONS: -Xms1024m -Xmx1024m -XX:+UseParallelGC -Djava.awt.headless=true Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,471 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,471 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/opt/openboxes/openboxes.war!/WEB-INF/classes!/logback.xml] Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,490 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@6bdf28bb - URL [jar:file:/opt/openboxes/openboxes.war!/WEB-INF/classes!/logback.xml] is not of type file Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,661 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,662 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [io.sentry.logback.SentryAppender] Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,745 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [sentry] Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,799 |-WARN in io.sentry.logback.SentryAppender[sentry] - Failed to init Sentry during appender initialization: DSN is required. Use empty string to disable SDK. Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,799 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,801 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdout] Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,802 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,831 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [openboxes] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.pih.warehouse] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [testutils] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [util] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [asset.pipeline] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ch.qos.logback] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [grails.app.services] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [grails.artefact] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [grails.boot] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [grails.plugins] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [grails.util] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [grails.web] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.changelog] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.database] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.executor] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.license] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.lockservice] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.parser] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.resource] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.servicelocator] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [liquibase.util] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [net.sf.ehcache] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.catalina.authenticator] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.catalina.connector] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.catalina.core] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.catalina.loader] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.catalina.realm] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.catalina.session] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.coyote] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.jasper] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,832 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.tomcat.pool] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.tomcat.util.digester] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.tomcat.util.http] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.tomcat.util.modeler] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.tomcat.util.net] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.tomcat.util.scan] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.tomcat.util.threads] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.config] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.core.io] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.datastore] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.datastore.mapping.config] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.gsp] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.orm.hibernate] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.orm.hibernate5] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.plugins.web] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.spring] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.grails.web] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.boot] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.cache] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.cfg] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.engine] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.event] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.hql] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.id] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.integrator] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.internal] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.loader] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.mapping] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.persister] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.query] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.resource] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.secure] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,833 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.service] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.SQL] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.stat] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.transform] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.tool] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.type] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate.validator] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.aop] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.beans] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.boot.actuate] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.boot.autoconfigure] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.boot.context] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.boot.env] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.boot.liquibase] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.boot.logging] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.boot.web] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.context] to WARN Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.core] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.jdbc] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.jmx] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.jndi] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.ui] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.web] to INFO Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [quartz] to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to DEBUG Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,834 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [sentry] to Logger[ROOT] Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,835 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdout] to Logger[ROOT] Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,835 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. Jul 21 19:43:40 OB-VM java[30566]: 19:43:40,836 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6b71769e - Registering current configuration as safe fallback point Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,243 INFO [main ] g.plugin.externalconfig.ExternalConfig : Loading properties config file jar:file:/opt/openboxes/openboxes.war!/WEB-INF/classes!/META-INF/grails.build.info Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,274 DEBUG [main ] g.plugin.externalconfig.ExternalConfig : Config file file:${catalina.base}/.grails/openboxes-config.properties not found Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,290 DEBUG [main ] g.plugin.externalconfig.ExternalConfig : Config file file:${catalina.base}/.grails/openboxes-config.groovy not found Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,303 DEBUG [main ] g.plugin.externalconfig.ExternalConfig : Config file file:${catalina.base}/.grails/openboxes.yml not found Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,325 DEBUG [main ] g.plugin.externalconfig.ExternalConfig : Config file file:/opt/openboxes/.grails/openboxes-config.properties not found Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,340 DEBUG [main ] g.plugin.externalconfig.ExternalConfig : Config file file:/opt/openboxes/.grails/openboxes-config.groovy not found Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,353 INFO [main ] g.plugin.externalconfig.ExternalConfig : Loading YAML config file file:/opt/openboxes/.grails/openboxes.yml Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,555 INFO [main ] org.pih.warehouse.Application : Starting Application on OB-VM with PID 30566 (/opt/openboxes/openboxes.war started by openboxes in /opt/openboxes) Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,556 DEBUG [main ] org.pih.warehouse.Application : Running with Spring Boot v1.5.22.RELEASE, Spring v4.3.30.RELEASE Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,556 INFO [main ] org.pih.warehouse.Application : The following profiles are active: production Jul 21 19:43:43 OB-VM java[30566]: 2023-07-21 19:43:43,557 DEBUG [main ] o.springframework.boot.SpringApplication: Loading source class org.pih.warehouse.Application Jul 21 19:43:44 OB-VM java[30566]: 2023-07-21 19:43:44,127 DEBUG [nd-preinit] org.jboss.logging : Logging Provider: org.jboss.logging.Slf4jLoggerProvider found via system property Jul 21 19:43:44 OB-VM java[30566]: 2023-07-21 19:43:44,127 INFO [nd-preinit] o.h.validator.internal.util.Version : HV000001: Hibernate Validator 5.3.6.Final Jul 21 19:43:54 OB-VM java[30566]: 2023-07-21 19:43:54,693 DEBUG [main ] org.grails.plugins.CorePluginFinder : Attempting to load [34] core plugins Jul 21 19:43:54 OB-VM java[30566]: 2023-07-21 19:43:54,872 DEBUG [main ] org.grails.plugins.DefaultGrailsPlugin : Configuring plugin [interceptors:3.3.16] to watch resources with pattern: file:./grails-app/controllers/**/*Interceptor.groovy Jul 21 19:43:54 OB-VM java[30566]: 2023-07-21 19:43:54,906 DEBUG [main ] org.grails.plugins.DefaultGrailsPlugin : Configuring plugin [quartz:2.0.13] to watch resources with pattern: file:./grails-app/jobs/**/*Job.groovy Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,083 DEBUG [main ] org.grails.plugins.DefaultGrailsPlugin : Configuring plugin [i18n:3.3.16] to watch resources with pattern: file:./grails-app/i18n/**/*.properties Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,154 DEBUG [main ] org.grails.plugins.DefaultGrailsPlugin : Configuring plugin [codecs:3.3.16] to watch resources with pattern: file:./grails-app/utils/**/*Codec.groovy Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,260 INFO [main ] g.plugins.DefaultGrailsPluginManager : Attempting to load [0] user defined plugins Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,261 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [databaseMigration] with version [unspecified] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,262 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [excelImport] with version [3.0.2] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,262 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [core] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,262 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [restResponder] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,262 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [browserDetection] with version [3.4.0] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,264 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [dataSource] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,264 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [externalConfig] with version [1.4.0] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,264 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [fields] with version [2.2.8] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,265 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [eventBus] with version [3.3.2] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,265 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [i18n] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,265 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [grailsPrettyTime] with version [4.0.0] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,266 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [codecs] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,266 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [jodaTime] with version [2.1.0-SNAPSHOT] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,266 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [dataBinding] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,266 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [jsonView] with version [1.2.10] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,267 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [console] with version [unspecified] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,267 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [ajaxTags] with version [1.0.0] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,267 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [urlMappings] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,268 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [groovyPages] with version [3.3.2] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,268 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [controllers] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,268 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [rendering] with version [2.0.3] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,268 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [assetPipeline] with version [3.2.3] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,270 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [csv] with version [1.0.1] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,271 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [scaffolding] with version [3.4.1] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,272 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [domainClass] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,272 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [mimeTypes] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,272 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [controllersAsync] with version [3.3.2] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,273 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [hibernate] with version [6.1.12] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,273 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [converters] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,273 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [interceptors] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,273 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [services] with version [3.3.16] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,273 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [cache] with version [4.0.1] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,273 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [quartz] with version [2.0.13] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,274 INFO [main ] g.plugins.DefaultGrailsPluginManager : Grails plug-in [quartzMonitor] with version [1.3] loaded successfully Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,334 DEBUG [main ] grails.core.DefaultGrailsApplication : loaded classes: [[]] Jul 21 19:43:55 OB-VM java[30566]: 2023-07-21 19:43:55,416 DEBUG [main ] grails.core.DefaultGrailsApplication : Going to inspect artefact classes. Jul 21 19:43:57 OB-VM java[30566]: 2023-07-21 19:43:57,748 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:57 OB-VM java[30566]: 2023-07-21 19:43:57,753 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:58 OB-VM java[30566]: 2023-07-21 19:43:58,355 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:58 OB-VM java[30566]: 2023-07-21 19:43:58,356 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:58 OB-VM java[30566]: 2023-07-21 19:43:58,883 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:58 OB-VM java[30566]: 2023-07-21 19:43:58,883 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:58 OB-VM java[30566]: 2023-07-21 19:43:58,980 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:58 OB-VM java[30566]: 2023-07-21 19:43:58,980 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,030 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,031 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,062 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,063 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,319 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,320 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,637 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,638 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,708 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,709 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,743 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,744 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,765 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:43:59 OB-VM java[30566]: 2023-07-21 19:43:59,766 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,032 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,033 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,034 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DatePickerTagLib.g:datePicker vs. org.grails.plugins.web.taglib.FormTagLib.g:datePicker. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,339 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,340 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,340 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DatePickerTagLib.g:datePicker vs. org.grails.plugins.web.taglib.FormTagLib.g:datePicker. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,415 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,415 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,416 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DatePickerTagLib.g:datePicker vs. org.grails.plugins.web.taglib.FormTagLib.g:datePicker. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,431 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.LinkTagLib.g:link vs. org.grails.plugins.web.taglib.ApplicationTagLib.g:link. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,432 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DateTagLib.g:formatDate vs. org.grails.plugins.web.taglib.FormatTagLib.g:formatDate. The former will take precedence. Jul 21 19:44:00 OB-VM java[30566]: 2023-07-21 19:44:00,432 INFO [main ] o.g.c.artefact.gsp.TagLibArtefactHandler: There are conflicting tags: org.pih.warehouse.DatePickerTagLib.g:datePicker vs. org.grails.plugins.web.taglib.FormTagLib.g:datePicker. The former will take precedence. Jul 21 19:44:02 OB-VM java[30566]: 2023-07-21 19:44:02,713 DEBUG [main ] quartz.QuartzGrailsPlugin : Registering hibernate SessionBinderJobListener Jul 21 19:44:02 OB-VM java[30566]: 2023-07-21 19:44:02,728 DEBUG [main ] org.grails.plugins.DefaultGrailsPlugin : Plugin [quartzMonitor:1.3] is participating in Spring configuration... Jul 21 19:44:02 OB-VM java[30566]: 2023-07-21 19:44:02,733 INFO [main ] o.s.b.f.s.DefaultListableBeanFactory : Overriding bean definition for bean 'multipartResolver' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.MultipartAutoConfiguration; factoryMethodName=multipartResolver; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/MultipartAutoConfiguration.class]] with [Generic bean: class [org.springframework.web.multipart.support.StandardServletMultipartResolver]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] Jul 21 19:44:02 OB-VM java[30566]: 2023-07-21 19:44:02,734 INFO [main ] o.s.b.f.s.DefaultListableBeanFactory : Overriding bean definition for bean 'characterEncodingFilter' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.HttpEncodingAutoConfiguration; factoryMethodName=characterEncodingFilter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/HttpEncodingAutoConfiguration.class]] with [Generic bean: class [org.springframework.boot.web.servlet.FilterRegistrationBean]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] Jul 21 19:44:02 OB-VM java[30566]: 2023-07-21 19:44:02,735 INFO [main ] o.s.b.f.s.DefaultListableBeanFactory : Overriding bean definition for bean 'propertySourcesPlaceholderConfigurer' with a different definition: replacing [Root bean: class [org.springframework.boot.autoconfigure.context.PropertyPlaceholderAutoConfiguration]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=propertySourcesPlaceholderConfigurer; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/context/PropertyPlaceholderAutoConfiguration.class]] with [Generic bean: class [org.grails.spring.context.support.GrailsPlaceholderConfigurer]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] Jul 21 19:44:02 OB-VM java[30566]: 2023-07-21 19:44:02,735 INFO [main ] o.s.b.f.s.DefaultListableBeanFactory : Overriding bean definition for bean 'multipartConfigElement' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.MultipartAutoConfiguration; factoryMethodName=multipartConfigElement; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/MultipartAutoConfiguration.class]] with [Generic bean: class [javax.servlet.MultipartConfigElement]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] Jul 21 19:44:02 OB-VM java[30566]: 2023-07-21 19:44:02,736 INFO [main ] o.s.b.f.s.DefaultListableBeanFactory : Overriding bean definition for bean 'dispatcherServlet' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.DispatcherServletAutoConfiguration$DispatcherServletConfiguration; factoryMethodName=dispatcherServlet; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/DispatcherServletAutoConfiguration$DispatcherServletConfiguration.class]] with [Generic bean: class [org.grails.web.servlet.mvc.GrailsDispatcherServlet]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] Jul 21 19:44:02 OB-VM java[30566]: 2023-07-21 19:44:02,737 INFO [main ] o.s.b.f.s.DefaultListableBeanFactory : Overriding bean definition for bean 'dispatcherServletRegistration' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.web.DispatcherServletAutoConfiguration$DispatcherServletRegistrationConfiguration; factoryMethodName=dispatcherServletRegistration; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/DispatcherServletAutoConfiguration$DispatcherServletRegistrationConfiguration.class]] with [Generic bean: class [org.springframework.boot.web.servlet.ServletRegistrationBean]; scope=singleton; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,499 DEBUG [main ] o.g.async.factory.PromiseFactoryBuilder : No PromiseFactory implementation found. Using default ExecutorService promise factory. Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,520 DEBUG [main ] o.g.e.bus.spring.EventBusFactoryBean : Creating event bus from PromiseFactory org.grails.async.factory.future.CachedThreadPoolPromiseFactory@aaa0f76 Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,595 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [grails.gorm.failOnError] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,596 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [script16899686217541827288011$_run_closure2@12704e15] for setting [grails.gorm.default.mapping] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,597 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [script16899686217541827288011$_run_closure1@512575e9] for setting [grails.gorm.default.constraints] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,655 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [[]] for setting [custom.types] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,655 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [[]] for setting [failOnErrorPackages] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,656 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [NONE] for setting [multiTenancy.mode] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,672 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [org.grails.datastore.mapping.multitenancy.resolvers.NoTenantResolver@6535117e] for setting [multiTenancy.tenantResolver] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,673 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [[]] for setting [custom.types] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,674 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [script16899686217541827288011$_run_closure2@12704e15] for setting [defaults.mapping] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,674 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [script16899686217541827288011$_run_closure1@512575e9] for setting [defaults.constraints] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,674 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [autowire] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,675 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [failOnError] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,675 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [NONE] for setting [multiTenancy.mode] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,675 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [org.grails.datastore.mapping.multitenancy.resolvers.NoTenantResolver@6fa13e6] for setting [multiTenancy.tenantResolver] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,676 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [AUTO] for setting [flushMode] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,676 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [script16899686217541827288011$_run_closure2@12704e15] for setting [default.mapping] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,676 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [script16899686217541827288011$_run_closure1@512575e9] for setting [default.constraints] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,676 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [script16899686217541827288011$_run_closure2@12704e15] for setting [defaults.mapping] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,677 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [script16899686217541827288011$_run_closure1@512575e9] for setting [defaults.constraints] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,677 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [autoFlush] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,680 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [hibernate.cache.queries] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,680 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [hibernate.cache.queries] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,681 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [{jmxEnabled=true, initialSize=5, maxActive=50, minIdle=5, maxIdle=25, maxWait=10000, maxAge=10 * 60000, timeBetweenEvictionRunsMillis=5000, minEvictableIdleTimeMillis=60000, validationQuery=SELECT 1, validationQueryTimeout=3, validationInterval=15000, testOnBorrow=false, testWhileIdle=true, testOnReturn=false, jdbcInterceptors=ConnectionState;StatementCache(max=200), defaultTransactionIsolation=java.sql.Connection.TRANSACTION_READ_COMMITTED}] for setting [dataSource.properties] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,682 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [jdbc:mysql://localhost:3306/openboxes?useSSL=false] for setting [dataSource.url] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,682 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [com.mysql.cj.jdbc.Driver] for setting [dataSource.driverClassName] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,705 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [class org.hibernate.dialect.MySQL57InnoDBDialect] for setting [dataSource.dialect] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,705 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [openboxes] for setting [dataSource.username] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,706 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [none] for setting [dataSource.dbCreate] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,706 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [dataSource.formatSql] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,707 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [true] for setting [dataSource.pooled] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,707 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [dataSource.logSql] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,708 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [class org.apache.tomcat.jdbc.pool.DataSource] for setting [dataSource.type] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,709 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [{jmxEnabled=true, initialSize=5, maxActive=50, minIdle=5, maxIdle=25, maxWait=10000, maxAge=10 * 60000, timeBetweenEvictionRunsMillis=5000, minEvictableIdleTimeMillis=60000, validationQuery=SELECT 1, validationQueryTimeout=3, validationInterval=15000, testOnBorrow=false, testWhileIdle=true, testOnReturn=false, jdbcInterceptors=ConnectionState;StatementCache(max=200), defaultTransactionIsolation=java.sql.Connection.TRANSACTION_READ_COMMITTED}] for setting [dataSource.properties] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,710 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [jdbc:mysql://localhost:3306/openboxes?useSSL=false] for setting [dataSource.url] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,710 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [com.mysql.cj.jdbc.Driver] for setting [dataSource.driverClassName] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,710 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [class org.hibernate.dialect.MySQL57InnoDBDialect] for setting [dataSource.dialect] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,711 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [openboxes] for setting [dataSource.username] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,711 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [none] for setting [dataSource.dbCreate] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,711 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [dataSource.formatSql] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,712 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [true] for setting [dataSource.pooled] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,712 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [dataSource.logSql] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,712 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [class org.apache.tomcat.jdbc.pool.DataSource] for setting [dataSource.type] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,713 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [hibernate.cache.queries] Jul 21 19:44:03 OB-VM java[30566]: 2023-07-21 19:44:03,713 DEBUG [main ] o.g.d.m.config.ConfigurationBuilder : Resolved value [false] for setting [hibernate.cache.queries] Jul 21 19:44:05 OB-VM java[30566]: 2023-07-21 19:44:05,944 INFO [main ] org.hibernate.Version : HHH000412: Hibernate Core {5.2.18.Final} Jul 21 19:44:05 OB-VM java[30566]: 2023-07-21 19:44:05,947 INFO [main ] org.hibernate.cfg.Environment : HHH000206: hibernate.properties not found Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,024 INFO [main ] o.h.v.internal.engine.ConfigurationImpl : HV000002: Ignoring XML configuration. Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,169 DEBUG [main ] org.hibernate.cfg.Configuration : Building session factory using provided StandardServiceRegistry Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,194 INFO [main ] org.hibernate.annotations.common.Version: HCANN000001: Hibernate Commons Annotations {5.0.1.Final} Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,322 INFO [main ] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.MySQL57InnoDBDialect Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,517 INFO [main ] o.g.orm.hibernate.cfg.GrailsDomainBinder: Mapping joined-subclass: org.pih.warehouse.core.User -> user Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,671 WARN [main ] org.hibernate.id.UUIDHexGenerator : HHH000409: Using org.hibernate.id.UUIDHexGenerator which does not generate IETF RFC 4122 compliant UUID values; consider using org.hibernate.id.UUIDGenerator instead Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,743 WARN [main ] org.hibernate.mapping.RootClass : HHH000038: Composite-id class does not override equals(): org.pih.warehouse.invoice.InvoiceList Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,744 WARN [main ] org.hibernate.mapping.RootClass : HHH000039: Composite-id class does not override hashCode(): org.pih.warehouse.invoice.InvoiceList Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,755 DEBUG [main ] org.hibernate.cfg.Settings : SessionFactory name : null Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,755 DEBUG [main ] org.hibernate.cfg.Settings : Automatic flush during beforeCompletion(): enabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,755 DEBUG [main ] org.hibernate.cfg.Settings : Automatic session close at end of transaction: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,756 DEBUG [main ] org.hibernate.cfg.Settings : Statistics: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,756 DEBUG [main ] org.hibernate.cfg.Settings : Deleted entity synthetic identifier rollback: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,757 DEBUG [main ] org.hibernate.cfg.Settings : Default entity-mode: pojo Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,757 DEBUG [main ] org.hibernate.cfg.Settings : Check Nullability in Core (should be disabled when Bean Validation is on): enabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,757 DEBUG [main ] org.hibernate.cfg.Settings : Allow initialization of lazy state outside session : disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,757 DEBUG [main ] org.hibernate.cfg.Settings : Using BatchFetchStyle : LEGACY Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,758 DEBUG [main ] org.hibernate.cfg.Settings : Default batch fetch size: -1 Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,758 DEBUG [main ] org.hibernate.cfg.Settings : Maximum outer join fetch depth: 2 Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,758 DEBUG [main ] org.hibernate.cfg.Settings : Default null ordering: NONE Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,758 DEBUG [main ] org.hibernate.cfg.Settings : Order SQL updates by primary key: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,759 DEBUG [main ] org.hibernate.cfg.Settings : Order SQL inserts for batching: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,759 DEBUG [main ] org.hibernate.cfg.Settings : multi-tenancy strategy : NONE Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,759 DEBUG [main ] org.hibernate.cfg.Settings : JTA Track by Thread: enabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,759 DEBUG [main ] org.hibernate.cfg.Settings : Query language substitutions: {} Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,759 DEBUG [main ] org.hibernate.cfg.Settings : JPA query language strict compliance: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,760 DEBUG [main ] org.hibernate.cfg.Settings : Named query checking : enabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,760 DEBUG [main ] org.hibernate.cfg.Settings : Second-level cache: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,760 DEBUG [main ] org.hibernate.cfg.Settings : Second-level query cache: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,760 DEBUG [main ] org.hibernate.cfg.Settings : Second-level query cache factory: null Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,761 DEBUG [main ] org.hibernate.cfg.Settings : Second-level cache region prefix: null Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,761 DEBUG [main ] org.hibernate.cfg.Settings : Optimize second-level cache for minimal puts: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,761 DEBUG [main ] org.hibernate.cfg.Settings : Structured second-level cache entries: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,761 DEBUG [main ] org.hibernate.cfg.Settings : Second-level cache direct-reference entries: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,762 DEBUG [main ] org.hibernate.cfg.Settings : Automatic eviction of collection cache: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,762 DEBUG [main ] org.hibernate.cfg.Settings : JDBC batch size: 15 Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,762 DEBUG [main ] org.hibernate.cfg.Settings : JDBC batch updates for versioned data: enabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,762 DEBUG [main ] org.hibernate.cfg.Settings : Scrollable result sets: enabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,762 DEBUG [main ] org.hibernate.cfg.Settings : Wrap result sets: disabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,763 DEBUG [main ] org.hibernate.cfg.Settings : JDBC3 getGeneratedKeys(): enabled Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,763 DEBUG [main ] org.hibernate.cfg.Settings : JDBC result set fetch size: null Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,763 DEBUG [main ] org.hibernate.cfg.Settings : Connection release mode: ON_CLOSE Jul 21 19:44:06 OB-VM java[30566]: 2023-07-21 19:44:06,763 DEBUG [main ] org.hibernate.cfg.Settings : Generate SQL with comments: disabled Jul 21 19:44:10 OB-VM java[30566]: 2023-07-21 19:44:10,497 INFO [main ] .g.o.h.GrailsHibernateTransactionManager: Using DataSource [org.springframework.jdbc.datasource.TransactionAwareDataSourceProxy@5b72fe6b] of Hibernate SessionFactory for HibernateTransactionManager Jul 21 19:44:10 OB-VM java[30566]: 2023-07-21 19:44:10,519 INFO [main ] o.h.v.internal.engine.ConfigurationImpl : HV000002: Ignoring XML configuration. Jul 21 19:44:10 OB-VM java[30566]: 2023-07-21 19:44:10,637 INFO [main ] o.s.b.f.xml.XmlBeanDefinitionReader : Loading XML bean definitions from class path resource [org/springframework/jdbc/support/sql-error-codes.xml] Jul 21 19:44:10 OB-VM java[30566]: 2023-07-21 19:44:10,811 INFO [main ] o.h.v.internal.engine.ConfigurationImpl : HV000002: Ignoring XML configuration. Jul 21 19:44:10 OB-VM java[30566]: 2023-07-21 19:44:10,817 INFO [main ] o.h.v.internal.engine.ConfigurationImpl : HV000002: Ignoring XML configuration. Jul 21 19:44:12 OB-VM java[30566]: 2023-07-21 19:44:12,145 INFO [main ] o.apache.coyote.http11.Http11NioProtocol: Initializing ProtocolHandler ["http-nio-8080"] Jul 21 19:44:12 OB-VM java[30566]: 2023-07-21 19:44:12,186 INFO [main ] org.apache.catalina.core.StandardService: Starting service [Tomcat] Jul 21 19:44:12 OB-VM java[30566]: 2023-07-21 19:44:12,187 INFO [main ] org.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/8.5.88] Jul 21 19:44:15 OB-VM java[30566]: 2023-07-21 19:44:15,709 INFO [tartStop-1] o.a.c.c.C.[.[localhost].[/openboxes] : Initializing Spring embedded WebApplicationContext Jul 21 19:44:15 OB-VM java[30566]: 2023-07-21 19:44:15,709 INFO [tartStop-1] o.s.web.context.ContextLoader : Root WebApplicationContext: initialization completed in 32034 ms Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,095 INFO [tartStop-1] o.s.b.web.servlet.FilterRegistrationBean: Mapping filter: 'metricsFilter' to: [/*] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,096 INFO [tartStop-1] o.s.b.web.servlet.FilterRegistrationBean: Mapping filter: 'grailsCorsFilter' to: [/*] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,097 INFO [tartStop-1] o.s.b.web.servlet.FilterRegistrationBean: Mapping filter: 'assetPipelineFilter' to urls: [/assets/*] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,097 INFO [tartStop-1] o.s.b.web.servlet.FilterRegistrationBean: Mapping filter: 'characterEncodingFilter' to urls: [/*] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,097 INFO [tartStop-1] o.s.b.web.servlet.FilterRegistrationBean: Mapping filter: 'hiddenHttpMethodFilter' to urls: [/*] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,098 INFO [tartStop-1] o.s.b.web.servlet.FilterRegistrationBean: Mapping filter: 'grailsWebRequestFilter' to urls: [/*] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,098 INFO [tartStop-1] o.s.b.web.servlet.FilterRegistrationBean: Mapping filter: 'webRequestLoggingFilter' to: [/*] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,098 INFO [tartStop-1] o.s.b.web.servlet.FilterRegistrationBean: Mapping filter: 'applicationContextIdFilter' to: [/*] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,099 INFO [tartStop-1] o.s.b.w.servlet.ServletRegistrationBean : Mapping servlet: 'grailsDispatcherServlet' to [/] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,101 INFO [tartStop-1] o.s.b.w.servlet.ServletRegistrationBean : Mapping servlet: 'groovyPagesServlet' to [*.gsp] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,130 DEBUG [tartStop-1] asset.pipeline.AssetPipelineFilter : Initializing filter 'assetPipelineFilter' Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,131 DEBUG [tartStop-1] asset.pipeline.AssetPipelineFilter : Filter 'assetPipelineFilter' configured successfully Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,809 INFO [main ] s.w.s.m.m.a.RequestMappingHandlerMapping: Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse) Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,811 INFO [main ] s.w.s.m.m.a.RequestMappingHandlerMapping: Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest) Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,865 INFO [main ] o.s.w.s.handler.SimpleUrlHandlerMapping : Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,866 INFO [main ] o.s.w.s.handler.SimpleUrlHandlerMapping : Mapped URL path [/static/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler] Jul 21 19:44:16 OB-VM java[30566]: 2023-07-21 19:44:16,938 INFO [main ] s.w.s.m.m.a.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@2654635: startup date [Fri Jul 21 19:43:43 UTC 2023]; root of context hierarchy Jul 21 19:44:18 OB-VM java[30566]: 2023-07-21 19:44:18,472 INFO [main ] o.s.ui.freemarker.SpringTemplateLoader : SpringTemplateLoader for FreeMarker: using resource loader [org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@2654635: startup date [Fri Jul 21 19:43:43 UTC 2023]; root of context hierarchy] and template loader path [classpath:/templates/] Jul 21 19:44:18 OB-VM java[30566]: 2023-07-21 19:44:18,473 INFO [main ] o.s.w.s.v.f.FreeMarkerConfigurer : ClassTemplateLoader for Spring macros added to FreeMarker configuration Jul 21 19:44:20 OB-VM java[30566]: 2023-07-21 19:44:20,786 INFO [main ] org.quartz.impl.StdSchedulerFactory : Using default implementation for ThreadExecutor Jul 21 19:44:20 OB-VM java[30566]: 2023-07-21 19:44:20,805 INFO [main ] org.quartz.core.SchedulerSignalerImpl : Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl Jul 21 19:44:20 OB-VM java[30566]: 2023-07-21 19:44:20,806 INFO [main ] org.quartz.core.QuartzScheduler : Quartz Scheduler v.2.3.2 created. Jul 21 19:44:20 OB-VM java[30566]: 2023-07-21 19:44:20,806 INFO [main ] org.quartz.simpl.RAMJobStore : RAMJobStore initialized. Jul 21 19:44:20 OB-VM java[30566]: 2023-07-21 19:44:20,807 INFO [main ] org.quartz.core.QuartzScheduler : Scheduler meta-data: Quartz Scheduler (v2.3.2) 'quartzScheduler' with instanceId 'NON_CLUSTERED' Jul 21 19:44:20 OB-VM java[30566]: Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. Jul 21 19:44:20 OB-VM java[30566]: NOT STARTED. Jul 21 19:44:20 OB-VM java[30566]: Currently in standby mode. Jul 21 19:44:20 OB-VM java[30566]: Number of jobs executed: 0 Jul 21 19:44:20 OB-VM java[30566]: Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads. Jul 21 19:44:20 OB-VM java[30566]: Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered. Jul 21 19:44:20 OB-VM java[30566]: 2023-07-21 19:44:20,808 INFO [main ] org.quartz.impl.StdSchedulerFactory : Quartz scheduler 'quartzScheduler' initialized from an externally provided properties instance. Jul 21 19:44:20 OB-VM java[30566]: 2023-07-21 19:44:20,808 INFO [main ] org.quartz.impl.StdSchedulerFactory : Quartz scheduler version: 2.3.2 Jul 21 19:44:20 OB-VM java[30566]: 2023-07-21 19:44:20,809 INFO [main ] org.quartz.core.QuartzScheduler : JobFactory set to: grails.plugins.quartz.QuartzMonitorJobFactory@2361fd67 Jul 21 19:44:21 OB-VM java[30566]: 2023-07-21 19:44:21,025 INFO [main ] s.w.s.m.m.a.RequestMappingHandlerMapping: Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse) Jul 21 19:44:21 OB-VM java[30566]: 2023-07-21 19:44:21,025 INFO [main ] s.w.s.m.m.a.RequestMappingHandlerMapping: Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest) Jul 21 19:44:21 OB-VM java[30566]: 2023-07-21 19:44:21,042 INFO [main ] s.w.s.m.m.a.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@2654635: startup date [Fri Jul 21 19:43:43 UTC 2023]; root of context hierarchy Jul 21 19:44:23 OB-VM java[30566]: 2023-07-21 19:44:23,742 INFO [main ] o.s.j.e.a.AnnotationMBeanExporter : Registering beans for JMX exposure on startup Jul 21 19:44:23 OB-VM java[30566]: 2023-07-21 19:44:23,755 INFO [main ] o.g.p.d.TomcatJDBCPoolMBeanExporter : Registering beans for JMX exposure on startup Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,223 DEBUG [main ] a.pipeline.fs.FileSystemAssetResolver : Asset Pipeline FSResolver Initialized with Scan Directories: [] Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,362 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.RefreshOrderSummaryJob with trigger GRAILS_TRIGGERS.refreshOrderSummaryJobCronTrigger: Trigger 'GRAILS_TRIGGERS.refreshOrderSummaryJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,365 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.RefreshOrderSummaryJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,377 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.CalculateHistoricalQuantityJob with trigger GRAILS_TRIGGERS.calculateHistoricalQuantityJobCronTrigger: Trigger 'GRAILS_TRIGGERS.calculateHistoricalQuantityJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,377 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.CalculateHistoricalQuantityJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,379 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.RefreshTransactionFactJob with trigger GRAILS_TRIGGERS.refreshTransactionFactJobCronTrigger: Trigger 'GRAILS_TRIGGERS.refreshTransactionFactJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,380 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.RefreshTransactionFactJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,381 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.SendStockAlertsJob with trigger GRAILS_TRIGGERS.sendStockAlertsJobCronTrigger: Trigger 'GRAILS_TRIGGERS.sendStockAlertsJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,382 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.SendStockAlertsJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,383 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.UpdateExchangeRatesJob with trigger GRAILS_TRIGGERS.updateExchangeRatesJobCronTrigger: Trigger 'GRAILS_TRIGGERS.updateExchangeRatesJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,384 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.UpdateExchangeRatesJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,387 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.AssignIdentifierJob with trigger GRAILS_TRIGGERS.assignIdentifierJobCronTrigger: Trigger 'GRAILS_TRIGGERS.assignIdentifierJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,387 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.AssignIdentifierJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,389 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.RefreshStockoutDataJob with trigger GRAILS_TRIGGERS.refreshStockoutDataJobCronTrigger: Trigger 'GRAILS_TRIGGERS.refreshStockoutDataJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,389 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.RefreshStockoutDataJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,391 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.RefreshDemandDataJob with trigger GRAILS_TRIGGERS.refreshDemandDataJobCronTrigger: Trigger 'GRAILS_TRIGGERS.refreshDemandDataJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,391 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.RefreshDemandDataJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,393 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduling org.pih.warehouse.jobs.DataCleaningJob with trigger GRAILS_TRIGGERS.dataCleaningJobCronTrigger: Trigger 'GRAILS_TRIGGERS.dataCleaningJobCronTrigger': triggerClass: 'org.quartz.impl.triggers.CronTriggerImpl calendar: 'null' misfireInstruction: 0 nextFireTime: null Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,393 DEBUG [main ] quartz.QuartzGrailsPlugin : Job org.pih.warehouse.jobs.DataCleaningJob scheduled Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,394 DEBUG [ulerThread] org.quartz.core.QuartzSchedulerThread : batch acquisition of 0 triggers Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,394 INFO [main ] org.quartz.core.QuartzScheduler : Scheduler quartzScheduler_$_NON_CLUSTERED started. Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,395 INFO [main ] quartz.QuartzGrailsPlugin : Quartz Scheduler - Started Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,396 DEBUG [main ] quartz.QuartzGrailsPlugin : Scheduled Job Classes count: 12 Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,543 INFO [main ] org.pih.warehouse.BootStrap : Running liquibase changelog(s) ... Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,590 INFO [main ] org.pih.warehouse.BootStrap : Liquibase running: false Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,591 INFO [main ] org.pih.warehouse.BootStrap : Setting default schema to openboxes Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,591 INFO [main ] org.pih.warehouse.BootStrap : Product Version: 5.7.42-0ubuntu0.18.04.1 Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,591 INFO [main ] org.pih.warehouse.BootStrap : Database Version: 5.7 (MySQL 5.7.42-0ubuntu0.18.04.1) Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,865 INFO [main ] org.pih.warehouse.BootStrap : executedChangelogVersions: [[version:views], [version:install], [version:0.9.x]] Jul 21 19:44:24 OB-VM java[30566]: 2023-07-21 19:44:24,866 INFO [main ] org.pih.warehouse.BootStrap : Dropping all views (will rebuild after migrations complete)... Jul 21 19:44:25 OB-VM java[30566]: 2023-07-21 19:44:25,335 INFO [main ] l.c.StandardChangeLogHistoryService : Reading from openboxes.DATABASECHANGELOG Jul 21 19:44:25 OB-VM java[30566]: 2023-07-21 19:44:25,410 INFO [main ] liquibase.changelog.ChangeSet : Custom SQL executed Jul 21 19:44:25 OB-VM java[30566]: 2023-07-21 19:44:25,411 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet views/drop-all-views.xml::1633402273161-1::jmiranda ran successfully in 34ms Jul 21 19:44:25 OB-VM java[30566]: 2023-07-21 19:44:25,445 INFO [main ] l.c.StandardChangeLogHistoryService : Reading from openboxes.DATABASECHANGELOG Jul 21 19:44:25 OB-VM java[30566]: 2023-07-21 19:44:25,464 INFO [main ] org.pih.warehouse.BootStrap : Running install changelog ... Jul 21 19:44:28 OB-VM java[30566]: 2023-07-21 19:44:28,910 INFO [main ] liquibase.changelog.ChangeSet : Custom SQL executed Jul 21 19:44:28 OB-VM java[30566]: 2023-07-21 19:44:28,910 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet install/changelog-disable-foreign-key-checks.groovy::disable-foreign-key-checks::jmiranda ran successfully in 1ms Jul 21 19:44:28 OB-VM java[30566]: 2023-07-21 19:44:28,923 INFO [main ] liquibase.changelog.ChangeSet : Custom SQL executed Jul 21 19:44:28 OB-VM java[30566]: 2023-07-21 19:44:28,923 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet install/changelog-insert-data-core.groovy::1580360689181-enable-foreign-key-checks::jmiranda (generated) ran successfully in 2ms Jul 21 19:44:28 OB-VM java[30566]: 2023-07-21 19:44:28,934 INFO [main ] liquibase.changelog.ChangeSet : Custom SQL executed Jul 21 19:44:28 OB-VM java[30566]: 2023-07-21 19:44:28,934 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet install/changelog-enable-foreign-key-checks.groovy::enable-foreign-key-checks::jmiranda ran successfully in 3ms Jul 21 19:44:28 OB-VM java[30566]: 2023-07-21 19:44:28,950 INFO [main ] org.pih.warehouse.BootStrap : Running latest updates Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,045 INFO [main ] l.c.StandardChangeLogHistoryService : Reading from openboxes.DATABASECHANGELOG Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,068 INFO [main ] org.pih.warehouse.BootStrap : Rebuilding views ... Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,137 INFO [main ] l.c.StandardChangeLogHistoryService : Reading from openboxes.DATABASECHANGELOG Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,264 INFO [main ] liquibase.changelog.ChangeSet : SQL in file views/product-demand-view.sql executed Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,265 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet views/changelog.xml::1580848680306-1::jmiranda ran successfully in 29ms Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,275 INFO [main ] liquibase.changelog.ChangeSet : Marking ChangeSet: views/changelog.xml::1580848680306-1b::jmiranda ran despite precondition failure due to onFail='MARK_RAN': Jul 21 19:44:29 OB-VM java[30566]: views/changelog.xml : Not precondition failed Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,295 INFO [main ] liquibase.changelog.ChangeSet : SQL in file views/product-inventory-snapshot-view.sql executed Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,295 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet views/changelog.xml::1580848680306-2::jmiranda ran successfully in 13ms Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,319 INFO [main ] liquibase.changelog.ChangeSet : SQL in file views/product-inventory-extended-view.sql executed Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,320 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet views/changelog.xml::1580848680306-3::jmiranda ran successfully in 15ms Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,348 INFO [main ] liquibase.changelog.ChangeSet : SQL in file views/product-inventory-pivot-view.sql executed Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,348 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet views/changelog.xml::1580848680306-4::jmiranda ran successfully in 17ms Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,365 INFO [main ] liquibase.changelog.ChangeSet : SQL in file views/product-inventory-compare-view.sql executed Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,366 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet views/changelog.xml::1580848680306-5::jmiranda ran successfully in 10ms Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,383 INFO [main ] liquibase.changelog.ChangeSet : SQL in file views/product-inventory-expiry-view.sql executed Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,384 INFO [main ] liquibase.changelog.ChangeSet : ChangeSet views/changelog.xml::1580848680306-6::jmiranda ran successfully in 11ms Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,410 ERROR [main ] liquibase.changelog.ChangeSet : Change Set views/changelog.xml::1580848680306-7::jmiranda failed. Error: Unknown column 'order.order_type_id' in 'where clause' [Failed SQL: (1054) CREATE OR REPLACE VIEW on_order_order_item_summary AS Jul 21 19:44:29 OB-VM java[30566]: ( Jul 21 19:44:29 OB-VM java[30566]: SELECT a.product_id, Jul 21 19:44:29 OB-VM java[30566]: a.destination_id, Jul 21 19:44:29 OB-VM java[30566]: ifnull(sum(case Jul 21 19:44:29 OB-VM java[30566]: when a.quantity_ordered - a.quantity_shipped > 0 Jul 21 19:44:29 OB-VM java[30566]: then a.quantity_ordered - a.quantity_shipped Jul 21 19:44:29 OB-VM java[30566]: else 0 end), 0) as quantity_ordered_not_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM ( Jul 21 19:44:29 OB-VM java[30566]: SELECT product.id as product_id, Jul 21 19:44:29 OB-VM java[30566]: order_item.id, Jul 21 19:44:29 OB-VM java[30566]: `order`.destination_id, Jul 21 19:44:29 OB-VM java[30566]: sum(distinct case Jul 21 19:44:29 OB-VM java[30566]: when `order`.status != 'PENDING' Jul 21 19:44:29 OB-VM java[30566]: then order_item.quantity * order_item.quantity_per_uom Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_ordered, Jul 21 19:44:29 OB-VM java[30566]: sum(case Jul 21 19:44:29 OB-VM java[30566]: when shipment_item.quantity and shipment.current_status in ('SHIPPED', 'PARTIALLY_RECEIVED', 'RECEIVED') then shipment_item.quantity Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM order_item Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: product ON order_item.product_id = product.id Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: `order` ON order_item.order_id = `order`.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: order_shipment ON order_shipment.order_item_id = order_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment_item ON order_shipment.shipment_item_id = shipment_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment ON shipment_item.shipment_id = shipment.id Jul 21 19:44:29 OB-VM java[30566]: WHERE `order`.order_type_id = 'PURCHASE_ORDER' Jul 21 19:44:29 OB-VM java[30566]: AND order_item.order_item_status_code != 'CANCELED' Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product.product_code, order_item.id, product.name, `order`.destination_id) a Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product_id, destination_id Jul 21 19:44:29 OB-VM java[30566]: )] Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,424 INFO [main ] org.pih.warehouse.BootStrap : Safely closing database Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,437 INFO [main ] utoConfigurationReportLoggingInitializer: Jul 21 19:44:29 OB-VM java[30566]: Error starting ApplicationContext. To display the auto-configuration report re-run your application with 'debug' enabled. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,444 ERROR [main ] o.springframework.boot.SpringApplication: Application startup failed Jul 21 19:44:29 OB-VM java[30566]: liquibase.exception.MigrationFailedException: Migration failed for change set views/changelog.xml::1580848680306-7::jmiranda: Jul 21 19:44:29 OB-VM java[30566]: Reason: liquibase.exception.DatabaseException: Unknown column 'order.order_type_id' in 'where clause' [Failed SQL: (1054) CREATE OR REPLACE VIEW on_order_order_item_summary AS Jul 21 19:44:29 OB-VM java[30566]: ( Jul 21 19:44:29 OB-VM java[30566]: SELECT a.product_id, Jul 21 19:44:29 OB-VM java[30566]: a.destination_id, Jul 21 19:44:29 OB-VM java[30566]: ifnull(sum(case Jul 21 19:44:29 OB-VM java[30566]: when a.quantity_ordered - a.quantity_shipped > 0 Jul 21 19:44:29 OB-VM java[30566]: then a.quantity_ordered - a.quantity_shipped Jul 21 19:44:29 OB-VM java[30566]: else 0 end), 0) as quantity_ordered_not_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM ( Jul 21 19:44:29 OB-VM java[30566]: SELECT product.id as product_id, Jul 21 19:44:29 OB-VM java[30566]: order_item.id, Jul 21 19:44:29 OB-VM java[30566]: `order`.destination_id, Jul 21 19:44:29 OB-VM java[30566]: sum(distinct case Jul 21 19:44:29 OB-VM java[30566]: when `order`.status != 'PENDING' Jul 21 19:44:29 OB-VM java[30566]: then order_item.quantity * order_item.quantity_per_uom Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_ordered, Jul 21 19:44:29 OB-VM java[30566]: sum(case Jul 21 19:44:29 OB-VM java[30566]: when shipment_item.quantity and shipment.current_status in ('SHIPPED', 'PARTIALLY_RECEIVED', 'RECEIVED') then shipment_item.quantity Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM order_item Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: product ON order_item.product_id = product.id Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: `order` ON order_item.order_id = `order`.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: order_shipment ON order_shipment.order_item_id = order_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment_item ON order_shipment.shipment_item_id = shipment_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment ON shipment_item.shipment_id = shipment.id Jul 21 19:44:29 OB-VM java[30566]: WHERE `order`.order_type_id = 'PURCHASE_ORDER' Jul 21 19:44:29 OB-VM java[30566]: AND order_item.order_item_status_code != 'CANCELED' Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product.product_code, order_item.id, product.name, `order`.destination_id) a Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product_id, destination_id Jul 21 19:44:29 OB-VM java[30566]: )] Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.changelog.ChangeSet.execute(ChangeSet.java:659) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.changelog.visitor.UpdateVisitor.visit(UpdateVisitor.java:53) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.changelog.ChangeLogIterator.run(ChangeLogIterator.java:97) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.Liquibase.update(Liquibase.java:201) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.Liquibase.update(Liquibase.java:178) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoCachedMethodSite.invoke(PojoMetaMethodSite.java:192) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:56) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:136) Jul 21 19:44:29 OB-VM java[30566]: #011at org.pih.warehouse.BootStrap$_closure1.doCall(BootStrap.groovy:638) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:98) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:325) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:264) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1034) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1099) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1034) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.Closure.call(Closure.java:420) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.Closure.call(Closure.java:414) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.util.Environment.evaluateEnvironmentSpecificBlock(Environment.java:541) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.util.Environment.executeForEnvironment(Environment.java:534) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.util.Environment.executeForCurrentEnvironment(Environment.java:510) Jul 21 19:44:29 OB-VM java[30566]: #011at org.grails.web.servlet.boostrap.DefaultGrailsBootstrapClass.callInit(DefaultGrailsBootstrapClass.java:74) Jul 21 19:44:29 OB-VM java[30566]: #011at org.grails.web.servlet.context.GrailsConfigUtils.executeGrailsBootstraps(GrailsConfigUtils.java:65) Jul 21 19:44:29 OB-VM java[30566]: #011at org.grails.plugins.web.servlet.context.BootStrapClassRunner.onStartup(BootStrapClassRunner.groovy:53) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.config.GrailsApplicationPostProcessor.onApplicationEvent(GrailsApplicationPostProcessor.groovy:263) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.config.GrailsApplicationPostProcessor.onApplicationEvent(GrailsApplicationPostProcessor.groovy) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.event.SimpleApplicationEventMulticaster.doInvokeListener(SimpleApplicationEventMulticaster.java:172) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.event.SimpleApplicationEventMulticaster.invokeListener(SimpleApplicationEventMulticaster.java:165) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:139) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:393) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:347) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:883) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.finishRefresh(EmbeddedWebApplicationContext.java:144) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:545) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.refresh(EmbeddedWebApplicationContext.java:123) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:666) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:353) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.SpringApplication.run(SpringApplication.java:300) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.GrailsApp.run(GrailsApp.groovy:84) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.GrailsApp.run(GrailsApp.groovy:393) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.GrailsApp.run(GrailsApp.groovy:380) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.GrailsApp$run.call(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:136) Jul 21 19:44:29 OB-VM java[30566]: #011at org.pih.warehouse.Application.main(Application.groovy:8) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.loader.Launcher.launch(Launcher.java:87) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.loader.Launcher.launch(Launcher.java:51) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.loader.WarLauncher.main(WarLauncher.java:59) Jul 21 19:44:29 OB-VM java[30566]: Caused by: liquibase.exception.DatabaseException: Unknown column 'order.order_type_id' in 'where clause' [Failed SQL: (1054) CREATE OR REPLACE VIEW on_order_order_item_summary AS Jul 21 19:44:29 OB-VM java[30566]: ( Jul 21 19:44:29 OB-VM java[30566]: SELECT a.product_id, Jul 21 19:44:29 OB-VM java[30566]: a.destination_id, Jul 21 19:44:29 OB-VM java[30566]: ifnull(sum(case Jul 21 19:44:29 OB-VM java[30566]: when a.quantity_ordered - a.quantity_shipped > 0 Jul 21 19:44:29 OB-VM java[30566]: then a.quantity_ordered - a.quantity_shipped Jul 21 19:44:29 OB-VM java[30566]: else 0 end), 0) as quantity_ordered_not_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM ( Jul 21 19:44:29 OB-VM java[30566]: SELECT product.id as product_id, Jul 21 19:44:29 OB-VM java[30566]: order_item.id, Jul 21 19:44:29 OB-VM java[30566]: `order`.destination_id, Jul 21 19:44:29 OB-VM java[30566]: sum(distinct case Jul 21 19:44:29 OB-VM java[30566]: when `order`.status != 'PENDING' Jul 21 19:44:29 OB-VM java[30566]: then order_item.quantity * order_item.quantity_per_uom Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_ordered, Jul 21 19:44:29 OB-VM java[30566]: sum(case Jul 21 19:44:29 OB-VM java[30566]: when shipment_item.quantity and shipment.current_status in ('SHIPPED', 'PARTIALLY_RECEIVED', 'RECEIVED') then shipment_item.quantity Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM order_item Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: product ON order_item.product_id = product.id Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: `order` ON order_item.order_id = `order`.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: order_shipment ON order_shipment.order_item_id = order_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment_item ON order_shipment.shipment_item_id = shipment_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment ON shipment_item.shipment_id = shipment.id Jul 21 19:44:29 OB-VM java[30566]: WHERE `order`.order_type_id = 'PURCHASE_ORDER' Jul 21 19:44:29 OB-VM java[30566]: AND order_item.order_item_status_code != 'CANCELED' Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product.product_code, order_item.id, product.name, `order`.destination_id) a Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product_id, destination_id Jul 21 19:44:29 OB-VM java[30566]: )] Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.executor.jvm.JdbcExecutor$ExecuteStatementCallback.doInStatement(JdbcExecutor.java:430) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.executor.jvm.JdbcExecutor.execute(JdbcExecutor.java:87) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.executor.jvm.JdbcExecutor.execute(JdbcExecutor.java:159) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.database.AbstractJdbcDatabase.execute(AbstractJdbcDatabase.java:1276) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.database.AbstractJdbcDatabase.executeStatements(AbstractJdbcDatabase.java:1258) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.changelog.ChangeSet.execute(ChangeSet.java:622) Jul 21 19:44:29 OB-VM java[30566]: #011... 62 common frames omitted Jul 21 19:44:29 OB-VM java[30566]: Caused by: java.sql.SQLSyntaxErrorException: Unknown column 'order.order_type_id' in 'where clause' Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.exceptions.SQLError.createSQLException(SQLError.java:120) Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.exceptions.SQLError.createSQLException(SQLError.java:97) Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.exceptions.SQLExceptionsMapping.translateException(SQLExceptionsMapping.java:122) Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.StatementImpl.executeInternal(StatementImpl.java:764) Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.StatementImpl.execute(StatementImpl.java:648) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.GeneratedMethodAccessor225.invoke(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.apache.tomcat.jdbc.pool.interceptor.StatementDecoratorInterceptor$StatementProxy.invoke(StatementDecoratorInterceptor.java:238) Jul 21 19:44:29 OB-VM java[30566]: #011at com.sun.proxy.$Proxy107.execute(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.GeneratedMethodAccessor225.invoke(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.apache.tomcat.jdbc.pool.StatementFacade$StatementProxy.invoke(StatementFacade.java:118) Jul 21 19:44:29 OB-VM java[30566]: #011at com.sun.proxy.$Proxy107.execute(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.executor.jvm.JdbcExecutor$ExecuteStatementCallback.doInStatement(JdbcExecutor.java:426) Jul 21 19:44:29 OB-VM java[30566]: #011... 67 common frames omitted Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,462 INFO [main ] org.quartz.core.QuartzScheduler : Scheduler quartzScheduler_$_NON_CLUSTERED shutting down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,463 INFO [main ] org.quartz.core.QuartzScheduler : Scheduler quartzScheduler_$_NON_CLUSTERED paused. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,464 DEBUG [main ] org.quartz.simpl.SimpleThreadPool : Shutting down threadpool... Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,810 DEBUG [r_Worker-3] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,826 DEBUG [r_Worker-1] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,826 DEBUG [_Worker-10] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,810 DEBUG [r_Worker-8] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,827 DEBUG [r_Worker-2] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,810 DEBUG [r_Worker-5] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,827 DEBUG [r_Worker-7] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,810 DEBUG [r_Worker-6] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,826 DEBUG [r_Worker-9] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,826 DEBUG [r_Worker-4] org.quartz.simpl.SimpleThreadPool : WorkerThread is shut down. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,828 DEBUG [main ] org.quartz.simpl.SimpleThreadPool : No executing jobs remaining, all threads stopped. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,828 DEBUG [main ] org.quartz.simpl.SimpleThreadPool : Shutdown of threadpool complete. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,828 INFO [main ] org.quartz.core.QuartzScheduler : Scheduler quartzScheduler_$_NON_CLUSTERED shutdown complete. Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,833 INFO [main ] o.g.p.d.TomcatJDBCPoolMBeanExporter : Unregistering JMX-exposed beans on shutdown Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,833 INFO [main ] o.s.s.quartz.SchedulerFactoryBean : Shutting down Quartz Scheduler Jul 21 19:44:29 OB-VM java[30566]: 2023-07-21 19:44:29,842 INFO [main ] o.s.j.e.a.AnnotationMBeanExporter : Unregistering JMX-exposed beans on shutdown Jul 21 19:44:29 OB-VM java[30566]: Exception in thread "main" java.lang.reflect.InvocationTargetException Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.loader.Launcher.launch(Launcher.java:87) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.loader.Launcher.launch(Launcher.java:51) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.loader.WarLauncher.main(WarLauncher.java:59) Jul 21 19:44:29 OB-VM java[30566]: Caused by: java.lang.reflect.UndeclaredThrowableException Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.util.ReflectionUtils.rethrowRuntimeException(ReflectionUtils.java:316) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.SpringApplication.handleRunFailure(SpringApplication.java:728) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.SpringApplication.run(SpringApplication.java:310) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.GrailsApp.run(GrailsApp.groovy:84) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.GrailsApp.run(GrailsApp.groovy:393) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.GrailsApp.run(GrailsApp.groovy:380) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.GrailsApp$run.call(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:136) Jul 21 19:44:29 OB-VM java[30566]: #011at org.pih.warehouse.Application.main(Application.groovy:8) Jul 21 19:44:29 OB-VM java[30566]: #011... 8 more Jul 21 19:44:29 OB-VM java[30566]: Caused by: liquibase.exception.MigrationFailedException: Migration failed for change set views/changelog.xml::1580848680306-7::jmiranda: Jul 21 19:44:29 OB-VM java[30566]: Reason: liquibase.exception.DatabaseException: Unknown column 'order.order_type_id' in 'where clause' [Failed SQL: (1054) CREATE OR REPLACE VIEW on_order_order_item_summary AS Jul 21 19:44:29 OB-VM java[30566]: ( Jul 21 19:44:29 OB-VM java[30566]: SELECT a.product_id, Jul 21 19:44:29 OB-VM java[30566]: a.destination_id, Jul 21 19:44:29 OB-VM java[30566]: ifnull(sum(case Jul 21 19:44:29 OB-VM java[30566]: when a.quantity_ordered - a.quantity_shipped > 0 Jul 21 19:44:29 OB-VM java[30566]: then a.quantity_ordered - a.quantity_shipped Jul 21 19:44:29 OB-VM java[30566]: else 0 end), 0) as quantity_ordered_not_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM ( Jul 21 19:44:29 OB-VM java[30566]: SELECT product.id as product_id, Jul 21 19:44:29 OB-VM java[30566]: order_item.id, Jul 21 19:44:29 OB-VM java[30566]: `order`.destination_id, Jul 21 19:44:29 OB-VM java[30566]: sum(distinct case Jul 21 19:44:29 OB-VM java[30566]: when `order`.status != 'PENDING' Jul 21 19:44:29 OB-VM java[30566]: then order_item.quantity * order_item.quantity_per_uom Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_ordered, Jul 21 19:44:29 OB-VM java[30566]: sum(case Jul 21 19:44:29 OB-VM java[30566]: when shipment_item.quantity and shipment.current_status in ('SHIPPED', 'PARTIALLY_RECEIVED', 'RECEIVED') then shipment_item.quantity Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM order_item Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: product ON order_item.product_id = product.id Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: `order` ON order_item.order_id = `order`.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: order_shipment ON order_shipment.order_item_id = order_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment_item ON order_shipment.shipment_item_id = shipment_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment ON shipment_item.shipment_id = shipment.id Jul 21 19:44:29 OB-VM java[30566]: WHERE `order`.order_type_id = 'PURCHASE_ORDER' Jul 21 19:44:29 OB-VM java[30566]: AND order_item.order_item_status_code != 'CANCELED' Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product.product_code, order_item.id, product.name, `order`.destination_id) a Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product_id, destination_id Jul 21 19:44:29 OB-VM java[30566]: )] Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.changelog.ChangeSet.execute(ChangeSet.java:659) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.changelog.visitor.UpdateVisitor.visit(UpdateVisitor.java:53) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.changelog.ChangeLogIterator.run(ChangeLogIterator.java:97) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.Liquibase.update(Liquibase.java:201) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.Liquibase.update(Liquibase.java:178) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoCachedMethodSite.invoke(PojoMetaMethodSite.java:192) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:56) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:136) Jul 21 19:44:29 OB-VM java[30566]: #011at org.pih.warehouse.BootStrap$_closure1.doCall(BootStrap.groovy:638) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:98) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:325) Jul 21 19:44:29 OB-VM java[30566]: #011at org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:264) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1034) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1099) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1034) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.Closure.call(Closure.java:420) Jul 21 19:44:29 OB-VM java[30566]: #011at groovy.lang.Closure.call(Closure.java:414) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.util.Environment.evaluateEnvironmentSpecificBlock(Environment.java:541) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.util.Environment.executeForEnvironment(Environment.java:534) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.util.Environment.executeForCurrentEnvironment(Environment.java:510) Jul 21 19:44:29 OB-VM java[30566]: #011at org.grails.web.servlet.boostrap.DefaultGrailsBootstrapClass.callInit(DefaultGrailsBootstrapClass.java:74) Jul 21 19:44:29 OB-VM java[30566]: #011at org.grails.web.servlet.context.GrailsConfigUtils.executeGrailsBootstraps(GrailsConfigUtils.java:65) Jul 21 19:44:29 OB-VM java[30566]: #011at org.grails.plugins.web.servlet.context.BootStrapClassRunner.onStartup(BootStrapClassRunner.groovy:53) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.config.GrailsApplicationPostProcessor.onApplicationEvent(GrailsApplicationPostProcessor.groovy:263) Jul 21 19:44:29 OB-VM java[30566]: #011at grails.boot.config.GrailsApplicationPostProcessor.onApplicationEvent(GrailsApplicationPostProcessor.groovy) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.event.SimpleApplicationEventMulticaster.doInvokeListener(SimpleApplicationEventMulticaster.java:172) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.event.SimpleApplicationEventMulticaster.invokeListener(SimpleApplicationEventMulticaster.java:165) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:139) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:393) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:347) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:883) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.finishRefresh(EmbeddedWebApplicationContext.java:144) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:545) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.refresh(EmbeddedWebApplicationContext.java:123) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:666) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:353) Jul 21 19:44:29 OB-VM java[30566]: #011at org.springframework.boot.SpringApplication.run(SpringApplication.java:300) Jul 21 19:44:29 OB-VM java[30566]: #011... 16 more Jul 21 19:44:29 OB-VM java[30566]: Caused by: liquibase.exception.DatabaseException: Unknown column 'order.order_type_id' in 'where clause' [Failed SQL: (1054) CREATE OR REPLACE VIEW on_order_order_item_summary AS Jul 21 19:44:29 OB-VM java[30566]: ( Jul 21 19:44:29 OB-VM java[30566]: SELECT a.product_id, Jul 21 19:44:29 OB-VM java[30566]: a.destination_id, Jul 21 19:44:29 OB-VM java[30566]: ifnull(sum(case Jul 21 19:44:29 OB-VM java[30566]: when a.quantity_ordered - a.quantity_shipped > 0 Jul 21 19:44:29 OB-VM java[30566]: then a.quantity_ordered - a.quantity_shipped Jul 21 19:44:29 OB-VM java[30566]: else 0 end), 0) as quantity_ordered_not_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM ( Jul 21 19:44:29 OB-VM java[30566]: SELECT product.id as product_id, Jul 21 19:44:29 OB-VM java[30566]: order_item.id, Jul 21 19:44:29 OB-VM java[30566]: `order`.destination_id, Jul 21 19:44:29 OB-VM java[30566]: sum(distinct case Jul 21 19:44:29 OB-VM java[30566]: when `order`.status != 'PENDING' Jul 21 19:44:29 OB-VM java[30566]: then order_item.quantity * order_item.quantity_per_uom Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_ordered, Jul 21 19:44:29 OB-VM java[30566]: sum(case Jul 21 19:44:29 OB-VM java[30566]: when shipment_item.quantity and shipment.current_status in ('SHIPPED', 'PARTIALLY_RECEIVED', 'RECEIVED') then shipment_item.quantity Jul 21 19:44:29 OB-VM java[30566]: else 0 end) as quantity_shipped, Jul 21 19:44:29 OB-VM java[30566]: null as quantity_shipped_not_received Jul 21 19:44:29 OB-VM java[30566]: FROM order_item Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: product ON order_item.product_id = product.id Jul 21 19:44:29 OB-VM java[30566]: JOIN Jul 21 19:44:29 OB-VM java[30566]: `order` ON order_item.order_id = `order`.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: order_shipment ON order_shipment.order_item_id = order_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment_item ON order_shipment.shipment_item_id = shipment_item.id Jul 21 19:44:29 OB-VM java[30566]: LEFT OUTER JOIN Jul 21 19:44:29 OB-VM java[30566]: shipment ON shipment_item.shipment_id = shipment.id Jul 21 19:44:29 OB-VM java[30566]: WHERE `order`.order_type_id = 'PURCHASE_ORDER' Jul 21 19:44:29 OB-VM java[30566]: AND order_item.order_item_status_code != 'CANCELED' Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product.product_code, order_item.id, product.name, `order`.destination_id) a Jul 21 19:44:29 OB-VM java[30566]: GROUP BY product_id, destination_id Jul 21 19:44:29 OB-VM java[30566]: )] Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.executor.jvm.JdbcExecutor$ExecuteStatementCallback.doInStatement(JdbcExecutor.java:430) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.executor.jvm.JdbcExecutor.execute(JdbcExecutor.java:87) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.executor.jvm.JdbcExecutor.execute(JdbcExecutor.java:159) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.database.AbstractJdbcDatabase.execute(AbstractJdbcDatabase.java:1276) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.database.AbstractJdbcDatabase.executeStatements(AbstractJdbcDatabase.java:1258) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.changelog.ChangeSet.execute(ChangeSet.java:622) Jul 21 19:44:29 OB-VM java[30566]: #011... 62 more Jul 21 19:44:29 OB-VM java[30566]: Caused by: java.sql.SQLSyntaxErrorException: Unknown column 'order.order_type_id' in 'where clause' Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.exceptions.SQLError.createSQLException(SQLError.java:120) Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.exceptions.SQLError.createSQLException(SQLError.java:97) Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.exceptions.SQLExceptionsMapping.translateException(SQLExceptionsMapping.java:122) Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.StatementImpl.executeInternal(StatementImpl.java:764) Jul 21 19:44:29 OB-VM java[30566]: #011at com.mysql.cj.jdbc.StatementImpl.execute(StatementImpl.java:648) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.GeneratedMethodAccessor225.invoke(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.apache.tomcat.jdbc.pool.interceptor.StatementDecoratorInterceptor$StatementProxy.invoke(StatementDecoratorInterceptor.java:238) Jul 21 19:44:29 OB-VM java[30566]: #011at com.sun.proxy.$Proxy107.execute(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.GeneratedMethodAccessor225.invoke(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) Jul 21 19:44:29 OB-VM java[30566]: #011at java.lang.reflect.Method.invoke(Method.java:498) Jul 21 19:44:29 OB-VM java[30566]: #011at org.apache.tomcat.jdbc.pool.StatementFacade$StatementProxy.invoke(StatementFacade.java:118) Jul 21 19:44:29 OB-VM java[30566]: #011at com.sun.proxy.$Proxy107.execute(Unknown Source) Jul 21 19:44:29 OB-VM java[30566]: #011at liquibase.executor.jvm.JdbcExecutor$ExecuteStatementCallback.doInStatement(JdbcExecutor.java:426) Jul 21 19:44:29 OB-VM java[30566]: #011... 67 more Jul 21 19:44:29 OB-VM systemd[1]: openboxes.service: Main process exited, code=exited, status=1/FAILURE Jul 21 19:44:29 OB-VM systemd[1]: openboxes.service: Failed with result 'exit-code'. Jul 21 19:44:40 OB-VM systemd[1]: openboxes.service: Service hold-off time over, scheduling restart. Jul 21 19:44:40 OB-VM systemd[1]: openboxes.service: Scheduled restart job, restart counter is at 20. Jul 21 19:44:40 OB-VM systemd[1]: Stopped OpenBoxes app.