19-Sep-2019 12:36:20.511 INFO [main] org.apache.catalina.core.StandardServer.await A valid shutdown command was received via the shutdown port. Stopping the Server instance.
19-Sep-2019 12:36:20.511 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-80"]
19-Sep-2019 12:36:20.518 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["http-nio-8080"]
19-Sep-2019 12:36:20.525 INFO [main] org.apache.coyote.AbstractProtocol.pause Pausing ProtocolHandler ["ajp-nio-8009"]
19-Sep-2019 12:36:20.531 INFO [main] org.apache.catalina.core.StandardService.stopInternal Stopping service [Catalina]
19-Sep-2019 12:36:20.532 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-80"]
19-Sep-2019 12:36:20.533 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["http-nio-8080"]
19-Sep-2019 12:36:20.535 INFO [main] org.apache.coyote.AbstractProtocol.stop Stopping ProtocolHandler ["ajp-nio-8009"]
19-Sep-2019 12:36:20.536 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-80"]
19-Sep-2019 12:36:20.537 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["http-nio-8080"]
19-Sep-2019 12:36:20.538 INFO [main] org.apache.coyote.AbstractProtocol.destroy Destroying ProtocolHandler ["ajp-nio-8009"]
19-Sep-2019 12:36:36.934 WARNING [main] org.apache.catalina.startup.SetAllPropertiesRule.begin [SetAllPropertiesRule]{Server/Service/Connector} Setting property 'compressableMimeType' to 'text/html,text/xml,text/plain,text/css,application/xml,application/xhtml+xml,application/rss+xml,application/javascript,application/x-javascript,image/svg+xml' did not find a matching property.
19-Sep-2019 12:36:36.940 WARNING [main] org.apache.catalina.startup.SetAllPropertiesRule.begin [SetAllPropertiesRule]{Server/Service/Connector} Setting property 'compressableMimeType' to 'text/html,text/xml,text/plain,text/css,application/xml,application/xhtml+xml,application/rss+xml,application/javascript,application/x-javascript,image/svg+xml' did not find a matching property.
19-Sep-2019 12:36:37.029 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent An older version [1.2.21] of the APR based Apache Tomcat Native library is installed, while Tomcat recommends a minimum version of [1.2.23]
19-Sep-2019 12:36:37.029 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded APR based Apache Tomcat Native library [1.2.21] using APR version [1.4.8].
19-Sep-2019 12:36:37.029 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
19-Sep-2019 12:36:37.029 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true]
19-Sep-2019 12:36:37.032 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.0.2k-fips  26 Jan 2017]
19-Sep-2019 12:36:37.634 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-80"]
19-Sep-2019 12:36:37.691 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"]
19-Sep-2019 12:36:37.693 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["ajp-nio-8009"]
19-Sep-2019 12:36:37.695 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [1,070] milliseconds
19-Sep-2019 12:36:37.729 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
19-Sep-2019 12:36:37.729 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.24]
19-Sep-2019 12:36:37.791 INFO [main] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [/opt/tomcat/webapps/ROOT.war]
SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/opt/tomcat/webapps/ROOT/WEB-INF/lib/logback-classic-1.2.3.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/opt/tomcat/webapps/ROOT/WEB-INF/lib/slf4j-log4j12-1.7.25.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
12:36:55,988 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
12:36:55,989 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
12:36:55,989 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/opt/tomcat/webapps/ROOT/WEB-INF/classes/logback.xml]
12:36:56,096 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
12:36:56,101 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [Console]
12:36:56,111 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
12:36:56,178 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [Console] to Logger[ROOT]
12:36:56,179 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba] to DEBUG
12:36:56,179 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.core.sys] to INFO
12:36:56,179 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.core.sys.CubaDefaultListableBeanFactory] to WARN
12:36:56,179 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.core.app.scheduling] to INFO
12:36:56,179 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.web.sys] to INFO
12:36:56,179 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.portal] to INFO
12:36:56,179 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.core.app.LockManager] to INFO
12:36:56,179 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [eclipselink] to WARN
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [eclipselink.sql] to INFO
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to WARN
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.vaadin] to WARN
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.atmosphere] to WARN
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.activiti] to INFO
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.jgroups] to INFO
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [freemarker] to INFO
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.thymeleaf.TemplateEngine] to INFO
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j] to WARN
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.xlsx4j] to WARN
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.fop.apps.FOUserAgent] to WARN
12:36:56,180 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to WARN
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [sun] to INFO
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.sun] to INFO
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [javax] to INFO
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache] to INFO
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.eclipse.jetty] to INFO
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.atmosphere] to INFO
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.utils.ResourceUtils] to ERROR
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.Docx4jProperties] to ERROR
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.xlsx4j.jaxb.Context] to ERROR
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.utils.XSLTUtils] to ERROR
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.jaxb.JaxbValidationEventHandler] to ERROR
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.TraversalUtil] to ERROR
12:36:56,181 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.fonts] to ERROR
12:36:56,182 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.perf4j.TimingLogger] to OFF
12:36:56,182 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.gui.logging.UIPerformanceLogger] to OFF
12:36:56,182 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
12:36:56,183 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@4a78d313 - Registering current configuration as safe fallback point
SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder]
12:36:55,924 |-INFO in ch.qos.logback.classic.servlet.LogbackServletContainerInitializer@710636b0 - Adding an instance of  class ch.qos.logback.classic.servlet.LogbackServletContextListener to the current web-app
19-Sep-2019 12:36:56.378 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
2019-09-19 12:36:56.707 INFO  [main] com.haulmont.cuba.core.sys.AppComponents - Using app components: [com.haulmont.cuba]
2019-09-19 12:36:56.802 INFO  [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - Loading app properties from classpath:ru/starlker/planb/app.properties
2019-09-19 12:36:56.805 INFO  [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - Loading app properties from /WEB-INF/local.app.properties
2019-09-19 12:36:57.625 INFO  [main] com.haulmont.cuba.core.sys.AppContextLoader - DbmsType of the main database is set to postgres
2019-09-19 12:36:57.805 INFO  [main] com.haulmont.cuba.core.sys.persistence.PersistenceConfigProcessor - Creating file /opt/tomcat/app-core/work/persistence.xml
2019-09-19 12:37:05.996 INFO  [main] com.haulmont.cuba.core.sys.CubaThreadPoolTaskScheduler - Initializing ExecutorService 'scheduler'
2019-09-19 12:37:06.286 INFO  [main] com.haulmont.cuba.core.sys.jmx.MBeanExporter - Registering beans for JMX exposure: [app-core.cuba:type=PersistenceManager, app-core.cuba:type=ConfigStorage, app-core.cuba:type=UserSessions, app-core.cuba:type=UniqueNumbers, app-core.cuba:type=Emailer, app-core.cuba:type=EntityLog, app-core.cuba:type=FileStorage, app-core.cuba:type=LockManager, app-core.cuba:type=CachingFacade, app-core.cuba:type=ClusterManager, app-core.cuba:type=ScriptingManager, app-core.cuba:type=ServerInfo, app-core.cuba:type=Scheduling, app-core.cuba:type=PasswordEncryptionSupport, app-core.cuba:type=JmxNodeIdentifier, app-core.cuba:type=JmxLogControl, app-core.cuba:type=ClassLoaderManager, app-core.cuba:type=StatisticsCounter, app-core.cuba:type=BruteForceProtection, app-core.cuba:type=QueryCacheSupport, app-core.cuba:type=ServerTokenStore]
2019-09-19 12:37:06.704 INFO  [main] com.haulmont.cuba.core.sys.MetadataImpl - Initializing metadata
2019-09-19 12:37:06.931 WARN  [main] com.haulmont.cuba.core.sys.MetaModelLoader - Field Terminology.deleteTs is not included in metadata because property sys$StandardEntity.deleteTs already exists
2019-09-19 12:37:06.931 WARN  [main] com.haulmont.cuba.core.sys.MetaModelLoader - Field Terminology.deletedBy is not included in metadata because property sys$StandardEntity.deletedBy already exists
2019-09-19 12:37:06.984 INFO  [main] com.haulmont.cuba.core.sys.MetadataImpl - Metadata initialized in 279 ms
2019-09-19 12:37:07.309 INFO  [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Updating database...
2019-09-19 12:37:07.577 INFO  [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Database is up-to-date
2019-09-19 12:37:07.582 DEBUG [main] com.haulmont.cuba.security.auth.AnonymousSessionHolder - Initialize anonymous session
2019-09-19 12:37:08.503 INFO  [main/app-core/server] com.haulmont.cuba.core.app.ConfigStorage - Loading DB-stored app properties cache
2019-09-19 12:37:09.013 INFO  [main/app-core/server] com.haulmont.cuba.core.sys.DefaultPermissionValuesConfig - Initializing default permission values
2019-09-19 12:37:09.106 INFO  [main/app-core/server] com.haulmont.cuba.security.auth.AuthenticationManagerBean - Logged in: 85ee1fd6-6185-9e21-cf7d-4755818aa9ec [anonymous]
2019-09-19 12:37:09.107 DEBUG [main] com.haulmont.cuba.security.auth.AnonymousSessionHolder - Anonymous session initialized with id 85ee1fd6-6185-9e21-cf7d-4755818aa9ec
2019-09-19 12:37:09.108 INFO  [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - AppContext started
2019-09-19 12:37:09.217 INFO  [main] com.haulmont.cuba.core.sys.remoting.RemotingServlet - Initializing Servlet 'remoting'
2019-09-19 12:37:09.309 DEBUG [cuba_core_scheduler-5] com.haulmont.cuba.core.app.queryresults.QueryResultsManager - Delete query results for inactive user sessions
2019-09-19 12:37:09.331 INFO  [main] com.haulmont.cuba.core.sys.remoting.RemoteServicesBeanCreator - Configuring remote services
2019-09-19 12:37:10.791 INFO  [main] com.haulmont.cuba.core.sys.remoting.RemotingServlet - Completed initialization in 1574 ms
2019-09-19 12:37:10.810 INFO  [main] com.haulmont.cuba.core.sys.AppComponents - Using app components: [com.haulmont.cuba]
2019-09-19 12:37:10.813 INFO  [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - Loading app properties from classpath:ru/starlker/planb/web-app.properties
2019-09-19 12:37:10.814 INFO  [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - Loading app properties from /WEB-INF/local.app.properties
2019-09-19 12:37:12.930 INFO  [main] com.haulmont.cuba.core.sys.remoting.discovery.StaticServerSelector - Server URLs: [http://localhost:8080/app-core/remoting]
2019-09-19 12:37:12.985 INFO  [main] com.haulmont.cuba.web.sys.remoting.WebRemoteProxyBeanCreator - Configuring proxy beans for local service invocations: [cuba_LoginService, cuba_AuthenticationService, cuba_TrustedClientService, cuba_ServerInfoService, cuba_UserSettingService, cuba_UserManagementService, cuba_DataService, cuba_ConfigStorageService, cuba_PersistenceManagerService, cuba_LockService, cuba_UserSessionService, cuba_FoldersService, cuba_EmailService, cuba_FileStorageService, cuba_EntitySnapshotService, cuba_UniqueNumbersService, cuba_LocalizedMessageService, cuba_ResourceService, cuba_ScheduledTasksService, cuba_EntityLogService, cuba_TestingService, cuba_DomainDescriptionService, cuba_RelatedEntitiesService, cuba_EntitySqlGenerationService, cuba_NumberIdService, cuba_QueryResultsService, cuba_DynamicAttributesCacheService, cuba_EntityRestoreService, cuba_LocalFileExchangeService, cuba_EntityImportExportService, cuba_ServerTokenStore, cuba_ConstraintLocalizationService, cuba_AttributeAccessService, cuba_ExceptionReportService, cuba_BulkEditorDataService]
2019-09-19 12:37:12.986 INFO  [main] com.haulmont.cuba.web.sys.remoting.WebRemoteProxyBeanCreator - Configuring proxy beans for local service invocations: [planb_AccountManagementService, planb_GenerateDocumentService, planb_UserLinkToProfileService]
2019-09-19 12:37:14.215 INFO  [main] com.haulmont.cuba.core.sys.CubaThreadPoolTaskScheduler - Initializing ExecutorService 'scheduler'
2019-09-19 12:37:14.429 INFO  [main] com.haulmont.cuba.core.sys.jmx.MBeanExporter - Registering beans for JMX exposure: [app.cuba:type=ConfigStorage, app.cuba:type=CachingFacade, app.cuba:type=FileUploading, app.cuba:type=JmxNodeIdentifier, app.cuba:type=JmxLogControl, app.cuba:type=ClassLoaderManager, app.cuba:type=StatisticsCounter]
2019-09-19 12:37:14.587 DEBUG [main] com.haulmont.cuba.gui.components.impl.ActionsImpl - Actions initialized in 55 ms
2019-09-19 12:37:14.588 INFO  [main] com.haulmont.cuba.core.sys.MetadataImpl - Initializing metadata
2019-09-19 12:37:14.897 WARN  [main] com.haulmont.cuba.core.sys.MetaModelLoader - Field Terminology.deleteTs is not included in metadata because property sys$StandardEntity.deleteTs already exists
2019-09-19 12:37:14.897 WARN  [main] com.haulmont.cuba.core.sys.MetaModelLoader - Field Terminology.deletedBy is not included in metadata because property sys$StandardEntity.deletedBy already exists
2019-09-19 12:37:14.909 INFO  [main] com.haulmont.cuba.core.sys.MetadataImpl - Metadata initialized in 321 ms
2019-09-19 12:37:14.977 INFO  [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - AppContext started
2019-09-19 12:37:15.027 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereHandler com.vaadin.server.communication.PushAtmosphereHandler mapped to context-path: /*
2019-09-19 12:37:15.027 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed the following AtmosphereInterceptor mapped to AtmosphereHandler com.vaadin.server.communication.PushAtmosphereHandler
2019-09-19 12:37:15.186 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Atmosphere is using org.atmosphere.util.VoidAnnotationProcessor for processing annotation
2019-09-19 12:37:15.202 INFO  [main] org.atmosphere.util.ForkJoinPool - Using ForkJoinPool  java.util.concurrent.ForkJoinPool. Set the org.atmosphere.cpr.broadcaster.maxAsyncWriteThreads to -1 to fully use its power.
2019-09-19 12:37:15.212 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed WebSocketProtocol org.atmosphere.websocket.protocol.SimpleHttpProtocol 
2019-09-19 12:37:15.219 INFO  [main] org.atmosphere.container.JSR356AsyncSupport - JSR 356 Mapping path 
2019-09-19 12:37:15.277 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installing Default AtmosphereInterceptors
2019-09-19 12:37:15.278 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.CorsInterceptor : CORS Interceptor Support
2019-09-19 12:37:15.279 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.CacheHeadersInterceptor : Default Response's Headers Interceptor
2019-09-19 12:37:15.281 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.PaddingAtmosphereInterceptor : Browser Padding Interceptor Support
2019-09-19 12:37:15.282 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.AndroidAtmosphereInterceptor : Android Interceptor Support
2019-09-19 12:37:15.285 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.HeartbeatInterceptor : Heartbeat Interceptor Support
2019-09-19 12:37:15.286 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.SSEAtmosphereInterceptor : SSE Interceptor Support
2019-09-19 12:37:15.286 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.JSONPAtmosphereInterceptor : JSONP Interceptor Support
2019-09-19 12:37:15.288 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.JavaScriptProtocol : Atmosphere JavaScript Protocol
2019-09-19 12:37:15.288 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.WebSocketMessageSuspendInterceptor : org.atmosphere.interceptor.WebSocketMessageSuspendInterceptor
2019-09-19 12:37:15.288 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.OnDisconnectInterceptor : Browser disconnection detection
2019-09-19 12:37:15.289 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - 	org.atmosphere.interceptor.IdleResourceInterceptor : org.atmosphere.interceptor.IdleResourceInterceptor
2019-09-19 12:37:15.289 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Set org.atmosphere.cpr.AtmosphereInterceptor.disableDefaults to disable them.
2019-09-19 12:37:15.290 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor CORS Interceptor Support with priority FIRST_BEFORE_DEFAULT 
2019-09-19 12:37:15.290 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Default Response's Headers Interceptor with priority AFTER_DEFAULT 
2019-09-19 12:37:15.291 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Browser Padding Interceptor Support with priority AFTER_DEFAULT 
2019-09-19 12:37:15.291 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Android Interceptor Support with priority AFTER_DEFAULT 
2019-09-19 12:37:15.291 INFO  [main] org.atmosphere.interceptor.HeartbeatInterceptor - HeartbeatInterceptor configured with padding value 'X', client frequency 60 seconds and server frequency 0 seconds
2019-09-19 12:37:15.291 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Heartbeat Interceptor Support with priority AFTER_DEFAULT 
2019-09-19 12:37:15.291 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor SSE Interceptor Support with priority AFTER_DEFAULT 
2019-09-19 12:37:15.292 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor JSONP Interceptor Support with priority AFTER_DEFAULT 
2019-09-19 12:37:15.292 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Atmosphere JavaScript Protocol with priority AFTER_DEFAULT 
2019-09-19 12:37:15.292 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor org.atmosphere.interceptor.WebSocketMessageSuspendInterceptor with priority AFTER_DEFAULT 
2019-09-19 12:37:15.292 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Browser disconnection detection with priority AFTER_DEFAULT 
2019-09-19 12:37:15.292 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor org.atmosphere.interceptor.IdleResourceInterceptor with priority BEFORE_DEFAULT 
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Using EndpointMapper class org.atmosphere.util.DefaultEndpointMapper
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Using BroadcasterCache: org.atmosphere.cache.UUIDBroadcasterCache
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Default Broadcaster Class: org.atmosphere.cpr.DefaultBroadcaster
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Broadcaster Shared List Resources: false
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Broadcaster Polling Wait Time 100
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Shared ExecutorService supported: true
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Messaging ExecutorService Pool Size unavailable - Not instance of ThreadPoolExecutor
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Async I/O Thread Pool Size: 200
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Using BroadcasterFactory: org.atmosphere.cpr.DefaultBroadcasterFactory
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Using AtmosphereResurceFactory: org.atmosphere.cpr.DefaultAtmosphereResourceFactory
2019-09-19 12:37:15.293 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Using WebSocketProcessor: org.atmosphere.websocket.DefaultWebSocketProcessor
2019-09-19 12:37:15.297 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Invoke AtmosphereInterceptor on WebSocket message true
2019-09-19 12:37:15.297 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - HttpSession supported: true
2019-09-19 12:37:15.297 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Atmosphere is using org.atmosphere.inject.InjectableObjectFactory for dependency injection and object creation
2019-09-19 12:37:15.297 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Atmosphere is using async support: org.atmosphere.container.JSR356AsyncSupport running under container: Apache Tomcat/9.0.24 using javax.servlet/3.0 and jsr356/WebSocket API
2019-09-19 12:37:15.297 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Atmosphere Framework 2.4.30-1-cuba started.
2019-09-19 12:37:15.303 INFO  [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor  Track Message Size Interceptor using | with priority BEFORE_DEFAULT 
2019-09-19 12:37:15.431 INFO  [main] com.haulmont.cuba.web.sys.singleapp.SingleAppDispatcherServlet - Initializing Servlet 'dispatcher'
2019-09-19 12:37:15.801 DEBUG [main] org.jboss.logging - Logging Provider: org.jboss.logging.Log4jLoggerProvider
2019-09-19 12:37:16.613 INFO  [main] com.haulmont.cuba.web.sys.singleapp.SingleAppDispatcherServlet - Completed initialization in 1180 ms
2019-09-19 12:37:16.620 INFO  [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Initializing Servlet 'rest_api'
2019-09-19 12:37:16.620 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Servlet with name 'rest_api' will try to create custom WebApplicationContext context of class 'com.haulmont.cuba.core.sys.CubaXmlWebApplicationContext', using parent context [com.haulmont.cuba.web.sys.singleapp.SingleAppWebContextLoader$1@6d244c88, started on Thu Sep 19 12:37:10 UTC 2019]
2019-09-19 12:37:16.621 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet$1 - Refreshing WebApplicationContext for namespace 'rest_api-servlet'
2019-09-19 12:37:18.013 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Detected CommonsMultipartResolver
2019-09-19 12:37:18.017 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Detected CommonsMultipartResolver
2019-09-19 12:37:18.020 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Published WebApplicationContext of servlet 'rest_api' as ServletContext attribute with name [org.springframework.web.servlet.FrameworkServlet.CONTEXT.rest_api]
2019-09-19 12:37:18.020 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - enableLoggingRequestDetails='false': request parameters and headers will be masked to prevent unsafe logging of potentially sensitive data
2019-09-19 12:37:18.020 INFO  [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Completed initialization in 1400 ms
2019-09-19 12:37:18.029 ERROR [main] com.haulmont.cuba.web.sys.singleapp.SingleAppWebContextLoader - Error initializing web servlets
java.lang.NullPointerException: null
	at ru.starlker.planb.web.servlets.CustomWebListener.registerApplicationServlet(CustomWebListener.java:29) ~[app-web-0.1-SNAPSHOT.jar:na]
	at ru.starlker.planb.web.servlets.CustomWebListener.contextInitialized(CustomWebListener.java:16) ~[app-web-0.1-SNAPSHOT.jar:na]
	at com.haulmont.cuba.web.sys.singleapp.SingleAppWebContextLoader.initWebServletContextListener(SingleAppWebContextLoader.java:256) ~[cuba-web-7.0.5.jar:7.0.5]
	at com.haulmont.cuba.web.sys.singleapp.SingleAppWebContextLoader.contextInitialized(SingleAppWebContextLoader.java:96) ~[cuba-web-7.0.5.jar:7.0.5]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_202]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_202]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_202]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_202]
	at org.springframework.util.ReflectionUtils.invokeMethod(ReflectionUtils.java:282) [spring-core-5.1.6.RELEASE.jar:5.1.6.RELEASE]
	at com.haulmont.cuba.web.sys.singleapp.SingleAppWebServletListener.contextInitialized(SingleAppWebServletListener.java:97) [cuba-web-7.0.5.jar:7.0.5]
	at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4685) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5146) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:978) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1849) [catalina.jar:9.0.24]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_202]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_202]
	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75) [tomcat-util.jar:9.0.24]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112) [na:1.8.0_202]
	at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:773) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:427) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1576) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:309) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:936) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:841) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1384) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1374) [catalina.jar:9.0.24]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_202]
	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75) [tomcat-util.jar:9.0.24]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134) [na:1.8.0_202]
	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:909) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:262) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardService.startInternal(StandardService.java:421) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:932) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.Catalina.start(Catalina.java:633) [catalina.jar:9.0.24]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_202]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_202]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_202]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_202]
	at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:344) [bootstrap.jar:9.0.24]
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:475) [bootstrap.jar:9.0.24]
2019-09-19 12:37:18.091 INFO  [main] com.haulmont.cuba.core.sys.CubaThreadPoolTaskScheduler - Shutting down ExecutorService 'scheduler'
2019-09-19 12:37:18.098 ERROR [main] com.haulmont.cuba.web.sys.singleapp.SingleAppWebServletListener - An error occurred while starting single WAR - web application
java.lang.NullPointerException: null
	at ru.starlker.planb.web.servlets.CustomWebListener.registerApplicationServlet(CustomWebListener.java:29) ~[app-web-0.1-SNAPSHOT.jar:na]
	at ru.starlker.planb.web.servlets.CustomWebListener.contextInitialized(CustomWebListener.java:16) ~[app-web-0.1-SNAPSHOT.jar:na]
	at com.haulmont.cuba.web.sys.singleapp.SingleAppWebContextLoader.initWebServletContextListener(SingleAppWebContextLoader.java:256) ~[cuba-web-7.0.5.jar:7.0.5]
	at com.haulmont.cuba.web.sys.singleapp.SingleAppWebContextLoader.contextInitialized(SingleAppWebContextLoader.java:96) ~[cuba-web-7.0.5.jar:7.0.5]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_202]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_202]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_202]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_202]
	at org.springframework.util.ReflectionUtils.invokeMethod(ReflectionUtils.java:282) ~[spring-core-5.1.6.RELEASE.jar:5.1.6.RELEASE]
	at com.haulmont.cuba.web.sys.singleapp.SingleAppWebServletListener.contextInitialized(SingleAppWebServletListener.java:97) ~[cuba-web-7.0.5.jar:7.0.5]
	at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4685) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5146) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:978) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1849) [catalina.jar:9.0.24]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_202]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_202]
	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75) [tomcat-util.jar:9.0.24]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112) [na:1.8.0_202]
	at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:773) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:427) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1576) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:309) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:936) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:841) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1384) [catalina.jar:9.0.24]
	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1374) [catalina.jar:9.0.24]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_202]
	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75) [tomcat-util.jar:9.0.24]
	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134) [na:1.8.0_202]
	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:909) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:262) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardService.startInternal(StandardService.java:421) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:932) [catalina.jar:9.0.24]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183) [catalina.jar:9.0.24]
	at org.apache.catalina.startup.Catalina.start(Catalina.java:633) [catalina.jar:9.0.24]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_202]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_202]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_202]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_202]
	at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:344) [bootstrap.jar:9.0.24]
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:475) [bootstrap.jar:9.0.24]
19-Sep-2019 12:37:18.099 SEVERE [main] org.apache.catalina.core.StandardContext.startInternal One or more listeners failed to start. Full details will be found in the appropriate container log file
19-Sep-2019 12:37:18.104 SEVERE [main] org.apache.catalina.core.StandardContext.startInternal Context [] startup failed due to previous errors
12:37:18,107 |-INFO in ch.qos.logback.classic.servlet.LogbackServletContextListener@14ce2b1f - About to stop ch.qos.logback.classic.LoggerContext [default]
19-Sep-2019 12:37:18.179 WARNING [main] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesJdbc The web application [ROOT] registered the JDBC driver [org.postgresql.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
19-Sep-2019 12:37:18.191 INFO [main] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [/opt/tomcat/webapps/ROOT.war] has finished in [40,400] ms
19-Sep-2019 12:37:18.194 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-80"]
19-Sep-2019 12:37:18.204 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
19-Sep-2019 12:37:18.208 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["ajp-nio-8009"]
19-Sep-2019 12:37:18.212 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in [40,516] milliseconds