30-Sep-2019 07:37:43.981 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. 30-Sep-2019 07:37:43.986 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. 30-Sep-2019 07:37:44.083 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] 30-Sep-2019 07:37:44.083 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent Loaded APR based Apache Tomcat Native library [1.2.21] using APR version [1.4.8]. 30-Sep-2019 07:37:44.083 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true]. 30-Sep-2019 07:37:44.083 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent APR/OpenSSL configuration: useAprConnector [false], useOpenSSL [true] 30-Sep-2019 07:37:44.087 INFO [main] org.apache.catalina.core.AprLifecycleListener.initializeSSL OpenSSL successfully initialized [OpenSSL 1.0.2k-fips 26 Jan 2017] 30-Sep-2019 07:37:44.622 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-80"] 30-Sep-2019 07:37:44.681 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"] 30-Sep-2019 07:37:44.682 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["ajp-nio-8009"] 30-Sep-2019 07:37:44.684 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [900] milliseconds 30-Sep-2019 07:37:44.705 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina] 30-Sep-2019 07:37:44.705 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.24] 30-Sep-2019 07:37:44.730 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. 07:38:11,801 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 07:38:11,806 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [Console] 07:38:11,814 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 07:38:11,894 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [Console] to Logger[ROOT] 07:38:11,895 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba] to DEBUG 07:38:11,895 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.core.sys] to INFO 07:38:11,896 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.core.sys.CubaDefaultListableBeanFactory] to WARN 07:38:11,896 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.core.app.scheduling] to INFO 07:38:11,896 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.web.sys] to INFO 07:38:11,896 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.portal] to INFO 07:38:11,896 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.core.app.LockManager] to INFO 07:38:11,896 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [eclipselink] to WARN 07:38:11,897 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [eclipselink.sql] to INFO 07:38:11,897 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to WARN 07:38:11,897 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.vaadin] to WARN 07:38:11,897 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.atmosphere] to WARN 07:38:11,897 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.activiti] to INFO 07:38:11,897 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.jgroups] to INFO 07:38:11,897 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [freemarker] to INFO 07:38:11,898 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.thymeleaf.TemplateEngine] to INFO 07:38:11,898 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j] to WARN 07:38:11,898 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.xlsx4j] to WARN 07:38:11,898 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache.fop.apps.FOUserAgent] to WARN 07:38:11,898 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to WARN 07:38:11,898 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [sun] to INFO 07:38:11,898 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.sun] to INFO 07:38:11,899 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [javax] to INFO 07:38:11,899 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.apache] to INFO 07:38:11,899 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.eclipse.jetty] to INFO 07:38:11,899 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.atmosphere] to INFO 07:38:11,899 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.utils.ResourceUtils] to ERROR 07:38:11,899 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.Docx4jProperties] to ERROR 07:38:11,900 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.xlsx4j.jaxb.Context] to ERROR 07:38:11,900 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.utils.XSLTUtils] to ERROR 07:38:11,900 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.jaxb.JaxbValidationEventHandler] to ERROR 07:38:11,900 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.TraversalUtil] to ERROR 07:38:11,900 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.docx4j.fonts] to ERROR 07:38:11,900 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.perf4j.TimingLogger] to OFF 07:38:11,900 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.haulmont.cuba.gui.logging.UIPerformanceLogger] to OFF 07:38:11,901 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 07:38:11,902 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@4720516f - Registering current configuration as safe fallback point SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder] 07:38:11,416 |-INFO in ch.qos.logback.classic.servlet.LogbackServletContainerInitializer@3901d134 - Adding an instance of class ch.qos.logback.classic.servlet.LogbackServletContextListener to the current web-app 30-Sep-2019 07:38:12.693 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-30 07:38:13.002 INFO [main] com.haulmont.cuba.core.sys.AppComponents - Using app components: [com.haulmont.cuba] 2019-09-30 07:38:13.036 INFO [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - Loading app properties from classpath:ru/starlker/planb/app.properties 2019-09-30 07:38:13.038 INFO [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - Loading app properties from /WEB-INF/local.app.properties 2019-09-30 07:38:13.117 INFO [main] com.haulmont.cuba.core.sys.AppContextLoader - DbmsType of the main database is set to postgres 2019-09-30 07:38:13.322 INFO [main] com.haulmont.cuba.core.sys.persistence.MappingFileCreator - Creating file /opt/tomcat/app-core/work/orm.xml 2019-09-30 07:38:13.329 INFO [main] com.haulmont.cuba.core.sys.persistence.PersistenceConfigProcessor - Creating file /opt/tomcat/app-core/work/persistence.xml 2019-09-30 07:38:23.225 INFO [main] com.haulmont.cuba.core.sys.CubaThreadPoolTaskScheduler - Initializing ExecutorService 'scheduler' 2019-09-30 07:38:23.595 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-30 07:38:24.178 INFO [main] com.haulmont.cuba.core.sys.MetadataImpl - Initializing metadata 2019-09-30 07:38:24.399 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-30 07:38:24.400 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-30 07:38:24.421 INFO [main] com.haulmont.cuba.core.sys.MetadataImpl - Metadata initialized in 242 ms 2019-09-30 07:38:25.497 INFO [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Initializing database 2019-09-30 07:38:25.712 INFO [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Executing script 10-cuba/init/postgres/create-db.sql 2019-09-30 07:38:26.004 INFO [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Executing script 50-planB/init/postgres/10.create-db.sql 2019-09-30 07:38:26.036 INFO [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Executing script 50-planB/init/postgres/20.create-db.sql 2019-09-30 07:38:26.119 INFO [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Executing script 50-planB/init/postgres/30.create-db.sql 2019-09-30 07:38:26.190 INFO [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Executing script 50-planB/init/postgres/40.create-db.sql 2019-09-30 07:38:27.785 INFO [main] com.haulmont.cuba.core.sys.dbupdate.DbUpdaterEngine - Database initialized 2019-09-30 07:38:27.791 DEBUG [main] com.haulmont.cuba.security.auth.AnonymousSessionHolder - Initialize anonymous session 2019-09-30 07:38:28.442 INFO [main/app-core/server] com.haulmont.cuba.core.app.ConfigStorage - Loading DB-stored app properties cache 2019-09-30 07:38:29.215 INFO [main/app-core/server] com.haulmont.cuba.core.sys.DefaultPermissionValuesConfig - Initializing default permission values 2019-09-30 07:38:29.322 INFO [main/app-core/server] com.haulmont.cuba.security.auth.AuthenticationManagerBean - Logged in: 85ee1fd6-6185-9e21-cf7d-4755818aa9ec [anonymous] 2019-09-30 07:38:29.323 DEBUG [main] com.haulmont.cuba.security.auth.AnonymousSessionHolder - Anonymous session initialized with id 85ee1fd6-6185-9e21-cf7d-4755818aa9ec 2019-09-30 07:38:29.323 INFO [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - AppContext started 2019-09-30 07:38:29.409 DEBUG [cuba_core_scheduler-5] com.haulmont.cuba.core.app.queryresults.QueryResultsManager - Delete query results for inactive user sessions 2019-09-30 07:38:29.517 INFO [main] com.haulmont.cuba.core.sys.remoting.RemotingServlet - Initializing Servlet 'remoting' 2019-09-30 07:38:29.623 INFO [main] com.haulmont.cuba.core.sys.remoting.RemoteServicesBeanCreator - Configuring remote services 2019-09-30 07:38:30.821 INFO [main] com.haulmont.cuba.core.sys.remoting.RemotingServlet - Completed initialization in 1297 ms 2019-09-30 07:38:30.884 INFO [main] com.haulmont.cuba.core.sys.AppComponents - Using app components: [com.haulmont.cuba] 2019-09-30 07:38:30.887 INFO [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - Loading app properties from classpath:ru/starlker/planb/web-app.properties 2019-09-30 07:38:30.888 INFO [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - Loading app properties from /WEB-INF/local.app.properties 2019-09-30 07:38:33.134 INFO [main] com.haulmont.cuba.core.sys.remoting.discovery.StaticServerSelector - Server URLs: [http://localhost:8080/app-core/remoting] 2019-09-30 07:38:33.185 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-30 07:38:33.186 INFO [main] com.haulmont.cuba.web.sys.remoting.WebRemoteProxyBeanCreator - Configuring proxy beans for local service invocations: [planb_AccountManagementService, planb_GenerateDocumentService, planb_UserLinkToProfileService] 2019-09-30 07:38:34.319 INFO [main] com.haulmont.cuba.core.sys.CubaThreadPoolTaskScheduler - Initializing ExecutorService 'scheduler' 2019-09-30 07:38:34.536 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-30 07:38:34.709 DEBUG [main] com.haulmont.cuba.gui.components.impl.ActionsImpl - Actions initialized in 28 ms 2019-09-30 07:38:34.710 INFO [main] com.haulmont.cuba.core.sys.MetadataImpl - Initializing metadata 2019-09-30 07:38:35.002 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-30 07:38:35.002 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-30 07:38:35.097 INFO [main] com.haulmont.cuba.core.sys.MetadataImpl - Metadata initialized in 387 ms 2019-09-30 07:38:35.120 INFO [main] com.haulmont.cuba.core.sys.AbstractWebAppContextLoader - AppContext started 2019-09-30 07:38:35.206 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereHandler com.vaadin.server.communication.PushAtmosphereHandler mapped to context-path: /* 2019-09-30 07:38:35.207 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed the following AtmosphereInterceptor mapped to AtmosphereHandler com.vaadin.server.communication.PushAtmosphereHandler 2019-09-30 07:38:35.380 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Atmosphere is using org.atmosphere.util.VoidAnnotationProcessor for processing annotation 2019-09-30 07:38:35.408 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-30 07:38:35.422 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed WebSocketProtocol org.atmosphere.websocket.protocol.SimpleHttpProtocol 2019-09-30 07:38:35.430 INFO [main] org.atmosphere.container.JSR356AsyncSupport - JSR 356 Mapping path 2019-09-30 07:38:35.482 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installing Default AtmosphereInterceptors 2019-09-30 07:38:35.483 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.CorsInterceptor : CORS Interceptor Support 2019-09-30 07:38:35.483 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.CacheHeadersInterceptor : Default Response's Headers Interceptor 2019-09-30 07:38:35.484 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.PaddingAtmosphereInterceptor : Browser Padding Interceptor Support 2019-09-30 07:38:35.485 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.AndroidAtmosphereInterceptor : Android Interceptor Support 2019-09-30 07:38:35.490 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.HeartbeatInterceptor : Heartbeat Interceptor Support 2019-09-30 07:38:35.491 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.SSEAtmosphereInterceptor : SSE Interceptor Support 2019-09-30 07:38:35.492 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.JSONPAtmosphereInterceptor : JSONP Interceptor Support 2019-09-30 07:38:35.496 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.JavaScriptProtocol : Atmosphere JavaScript Protocol 2019-09-30 07:38:35.497 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.WebSocketMessageSuspendInterceptor : org.atmosphere.interceptor.WebSocketMessageSuspendInterceptor 2019-09-30 07:38:35.497 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.OnDisconnectInterceptor : Browser disconnection detection 2019-09-30 07:38:35.498 INFO [main] org.atmosphere.cpr.AtmosphereFramework - org.atmosphere.interceptor.IdleResourceInterceptor : org.atmosphere.interceptor.IdleResourceInterceptor 2019-09-30 07:38:35.499 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Set org.atmosphere.cpr.AtmosphereInterceptor.disableDefaults to disable them. 2019-09-30 07:38:35.499 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor CORS Interceptor Support with priority FIRST_BEFORE_DEFAULT 2019-09-30 07:38:35.500 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Default Response's Headers Interceptor with priority AFTER_DEFAULT 2019-09-30 07:38:35.502 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Browser Padding Interceptor Support with priority AFTER_DEFAULT 2019-09-30 07:38:35.502 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Android Interceptor Support with priority AFTER_DEFAULT 2019-09-30 07:38:35.502 INFO [main] org.atmosphere.interceptor.HeartbeatInterceptor - HeartbeatInterceptor configured with padding value 'X', client frequency 60 seconds and server frequency 0 seconds 2019-09-30 07:38:35.502 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Heartbeat Interceptor Support with priority AFTER_DEFAULT 2019-09-30 07:38:35.502 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor SSE Interceptor Support with priority AFTER_DEFAULT 2019-09-30 07:38:35.502 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor JSONP Interceptor Support with priority AFTER_DEFAULT 2019-09-30 07:38:35.502 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Atmosphere JavaScript Protocol with priority AFTER_DEFAULT 2019-09-30 07:38:35.502 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor org.atmosphere.interceptor.WebSocketMessageSuspendInterceptor with priority AFTER_DEFAULT 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Browser disconnection detection with priority AFTER_DEFAULT 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor org.atmosphere.interceptor.IdleResourceInterceptor with priority BEFORE_DEFAULT 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Using EndpointMapper class org.atmosphere.util.DefaultEndpointMapper 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Using BroadcasterCache: org.atmosphere.cache.UUIDBroadcasterCache 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Default Broadcaster Class: org.atmosphere.cpr.DefaultBroadcaster 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Broadcaster Shared List Resources: false 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Broadcaster Polling Wait Time 100 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Shared ExecutorService supported: true 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Messaging ExecutorService Pool Size unavailable - Not instance of ThreadPoolExecutor 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Async I/O Thread Pool Size: 200 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Using BroadcasterFactory: org.atmosphere.cpr.DefaultBroadcasterFactory 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Using AtmosphereResurceFactory: org.atmosphere.cpr.DefaultAtmosphereResourceFactory 2019-09-30 07:38:35.503 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Using WebSocketProcessor: org.atmosphere.websocket.DefaultWebSocketProcessor 2019-09-30 07:38:35.509 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Invoke AtmosphereInterceptor on WebSocket message true 2019-09-30 07:38:35.509 INFO [main] org.atmosphere.cpr.AtmosphereFramework - HttpSession supported: true 2019-09-30 07:38:35.509 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Atmosphere is using org.atmosphere.inject.InjectableObjectFactory for dependency injection and object creation 2019-09-30 07:38:35.510 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-30 07:38:35.510 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Atmosphere Framework 2.4.30-1-cuba started. 2019-09-30 07:38:35.516 INFO [main] org.atmosphere.cpr.AtmosphereFramework - Installed AtmosphereInterceptor Track Message Size Interceptor using | with priority BEFORE_DEFAULT 2019-09-30 07:38:35.636 INFO [main] com.haulmont.cuba.web.sys.singleapp.SingleAppDispatcherServlet - Initializing Servlet 'dispatcher' 2019-09-30 07:38:36.079 DEBUG [main] org.jboss.logging - Logging Provider: org.jboss.logging.Log4jLoggerProvider 2019-09-30 07:38:37.489 INFO [main] com.haulmont.cuba.web.sys.singleapp.SingleAppDispatcherServlet - Completed initialization in 1853 ms 2019-09-30 07:38:37.507 INFO [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Initializing Servlet 'rest_api' 2019-09-30 07:38:37.508 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@7d34ce48, started on Mon Sep 30 07:38:30 UTC 2019] 2019-09-30 07:38:37.509 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet$1 - Refreshing WebApplicationContext for namespace 'rest_api-servlet' 2019-09-30 07:38:39.381 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Detected CommonsMultipartResolver 2019-09-30 07:38:39.387 DEBUG [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Detected CommonsMultipartResolver 2019-09-30 07:38:39.389 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-30 07:38:39.389 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-30 07:38:39.389 INFO [main] com.haulmont.restapi.sys.SingleAppRestApiServlet - Completed initialization in 1882 ms 30-Sep-2019 07:38:39.426 INFO [main] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [/opt/tomcat/webapps/ROOT.war] has finished in [54,696] ms 30-Sep-2019 07:38:39.435 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-80"] 30-Sep-2019 07:38:39.507 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"] 30-Sep-2019 07:38:39.515 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["ajp-nio-8009"] 30-Sep-2019 07:38:39.602 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in [54,918] milliseconds 2019-09-30 07:39:05.685 DEBUG [http-nio-80-exec-6] com.haulmont.cuba.web.App - Initializing application 2019-09-30 07:39:05.690 DEBUG [http-nio-80-exec-6] com.haulmont.cuba.gui.theme.ThemeConstantsRepository - Loading theme constants 2019-09-30 07:39:05.793 DEBUG [http-nio-80-exec-6] com.haulmont.cuba.web.security.WebAnonymousSessionHolder - Loading anonymous session 2019-09-30 07:39:06.031 DEBUG [http-nio-80-exec-6] com.haulmont.cuba.web.security.WebAnonymousSessionHolder - Anonymous session loaded with id 85ee1fd6-6185-9e21-cf7d-4755818aa9ec 2019-09-30 07:39:06.085 DEBUG [http-nio-80-exec-6/app/anonymous] com.haulmont.cuba.web.DefaultApp - connectionStateChanged connected: true, authenticated: false 2019-09-30 07:39:06.087 DEBUG [http-nio-80-exec-6/app/anonymous] com.haulmont.cuba.web.App - Closing all windows in all UIs 2019-09-30 07:39:06.130 INFO [http-nio-80-exec-6/app/anonymous] com.haulmont.cuba.gui.config.WindowConfig - WindowConfig initialized in 35 ms 2019-09-30 07:43:51.792 DEBUG [http-nio-80-exec-5] com.haulmont.cuba.web.App - Initializing application 2019-09-30 07:43:51.799 DEBUG [http-nio-80-exec-5/app/anonymous] com.haulmont.cuba.web.DefaultApp - connectionStateChanged connected: true, authenticated: false 2019-09-30 07:43:51.799 DEBUG [http-nio-80-exec-5/app/anonymous] com.haulmont.cuba.web.App - Closing all windows in all UIs 2019-09-30 07:43:51.912 INFO [ClientCacheManager-pool-3-thread-1/app-core/server] com.haulmont.cuba.security.auth.AuthenticationManagerBean - Logged in: 57db2766-33fc-9c38-d687-9e30d41531eb [admin] 2019-09-30 07:48:24.124 DEBUG [cuba_core_scheduler-5] com.haulmont.cuba.core.app.queryresults.QueryResultsManager - Delete query results for inactive user sessions