Starting Jetty server on port: 8079 and contextPath: /app-core LogbackConfigurator INFO Setting up CUBA default logging configuration LogbackConfigurator INFO Could NOT find resource [/opt/core-home/logback.xml] LogbackConfigurator INFO Found resource [jar:file:/opt/primaries/app-core.jar!/app-logback.xml] 09:02:56.883 INFO org.eclipse.jetty.util.log - Logging initialized @705ms to org.eclipse.jetty.util.log.Slf4jLog 09:02:57.060 INFO org.eclipse.jetty.server.Server - jetty-9.4.z-SNAPSHOT; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.7+10 09:02:57.193 INFO o.e.j.w.StandardDescriptorProcessor - NO JSP Support for /app-core, did not find org.eclipse.jetty.jsp.JettyJspServlet 09:02:57.248 INFO org.eclipse.jetty.server.session - DefaultSessionIdManager workerName=node0 09:02:57.249 INFO org.eclipse.jetty.server.session - No SessionScavenger set, using defaults 09:02:57.254 INFO org.eclipse.jetty.server.session - node0 Scavenging every 660000ms 09:02:57.269 INFO c.h.c.c.s.AbstractWebAppContextLoader - Initializing 'core' block, servlet context path: /app-core 09:02:57.404 INFO c.h.cuba.core.sys.AppComponents - Using app components: [com.haulmont.cuba, de.balvi.cuba.declarativecontrollers, com.haulmont.addon.helium, com.haulmont.addon.restapi, com.haulmont.addon.idp, com.haulmont.addon.globalevents, com.haulmont.addon.cubaaws, de.diedavids.cuba.dataimport, com.haulmont.addon.zookeeper] 09:02:57.449 INFO c.h.c.c.s.AbstractWebAppContextLoader - Loading app properties from classpath:com/ibec/primaries/app.properties 09:02:57.451 INFO c.h.c.c.s.AbstractWebAppContextLoader - Loading app properties from /WEB-INF/local.app.properties 09:02:57.462 INFO c.h.c.c.s.AbstractWebAppContextLoader - Loading app properties from file:/opt/core-home/local.app.properties 09:02:57.479 INFO c.h.c.c.s.AbstractWebAppContextLoader - cuba.webContextName: app-core 09:02:57.568 INFO c.h.cuba.core.sys.AppContextLoader - DbmsType of the main database is set to postgres 09:02:57.602 INFO c.h.c.c.s.e.DataStoresCheck - Checking connection to data store Main 09:02:57.708 INFO c.h.c.c.s.e.EnvironmentChecksRunner - Environment checks on core module completed successfully 09:02:57.891 INFO c.h.c.c.s.p.PersistenceConfigProcessor - Creating file /opt/core-home/app-core/work/persistence.xml 09:03:01.062 INFO com.zaxxer.hikari.HikariDataSource - Connection Pool-Main - Starting... 09:03:01.122 INFO com.zaxxer.hikari.HikariDataSource - Connection Pool-Main - Start completed. 09:03:04.337 INFO c.h.c.c.s.CubaThreadPoolTaskScheduler - Initializing ExecutorService 'scheduler' 09:03:04.466 INFO c.h.c.c.s.CubaThreadPoolTaskScheduler - Initializing ExecutorService 'restapi_scheduler' WARNING: An illegal reflective access operation has occurred WARNING: Illegal reflective access by org.codehaus.groovy.vmplugin.v7.Java7$1 (jar:file:/opt/primaries/app-core.jar!/LIB-INF/shared/) to constructor java.lang.invoke.MethodHandles$Lookup(java.lang.Class,int) WARNING: Please consider reporting this to the maintainers of org.codehaus.groovy.vmplugin.v7.Java7$1 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations WARNING: All illegal access operations will be denied in a future release 09:03:05.938 INFO c.h.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] 09:03:06.114 INFO c.h.cuba.core.sys.jmx.MBeanExporter - Registering beans for JMX exposure: [app-core.restapi:type=ServerTokenStore] 09:03:06.121 INFO c.h.cuba.core.sys.jmx.MBeanExporter - Registering beans for JMX exposure: [app-core.cuba:type=IdpSessions] 09:03:06.140 INFO c.h.cuba.core.sys.MetadataImpl - Initializing metadata 09:03:06.629 INFO c.h.cuba.core.sys.MetadataImpl - Metadata initialized in 487 ms 09:03:06.630 INFO c.h.cuba.core.app.ClusterManager - Starting cluster 09:03:06.632 INFO c.h.cuba.core.app.ClusterManager - Property 'cuba.cluster.jgroupsConfig' is not specified, using jgroups.xml 09:03:06.924 WARN org.jgroups.stack.Configurator - JGRP000014: GMS.view_bundling has been deprecated: view bundling is enabled by default 09:03:06.966 WARN org.jgroups.protocols.UDP - JGRP000015: the receive buffer of socket MulticastSocket was set to 500.00KB, but the OS only allocated 212.99KB ------------------------------------------------------------------- GMS: address=middleware-7b9f7468c8-hvwdg-16637, cluster=cubaCluster, physical address=172.17.0.9:43736 ------------------------------------------------------------------- 09:03:10.000 INFO org.jgroups.protocols.pbcast.GMS - middleware-7b9f7468c8-hvwdg-16637: no members discovered after 3014 ms: creating cluster as coordinator 09:03:10.018 INFO c.h.cuba.core.app.ClusterManager - New cluster view: [middleware-7b9f7468c8-hvwdg-16637|0] (1) [middleware-7b9f7468c8-hvwdg-16637] 09:03:10.025 INFO c.h.cuba.core.app.ClusterManager - Receiving cluster state... 09:03:11.249 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Initializing data store [Main] 09:03:11.265 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Executing script 10-cuba/init/postgres/create-db.sql 09:03:11.592 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Executing script 50-restapi/init/postgres/create-db.sql 09:03:11.601 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Executing script 60-cuba-component-data-import/init/postgres/10.create-db.sql 09:03:11.624 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Executing script 60-cuba-component-data-import/init/postgres/20.create-db.sql 09:03:11.668 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Executing script 60-cuba-component-data-import/init/postgres/30.create-db.sql 09:03:11.671 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Executing script 70-primaries/init/postgres/10.create-db.sql 09:03:11.792 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Executing script 70-primaries/init/postgres/20.create-db.sql 09:03:11.966 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Executing script 70-primaries/init/postgres/30.create-db.sql 09:03:12.474 INFO c.h.c.c.sys.dbupdate.DbUpdaterEngine - Data store [Main] initialized 09:03:13.335 INFO c.h.cuba.core.app.ConfigStorage - Loading DB-stored app properties cache 09:03:13.744 INFO c.h.c.s.a.AuthenticationManagerBean - Logged in: 6477a34a-bd59-734b-69b0-69cbbc81d796 [anonymous] 09:03:13.788 INFO c.h.c.s.a.AuthenticationManagerBean - Logged in: 65337d02-710b-5135-7ca6-51a98b0845ae [anonymous] 09:03:13.792 INFO c.h.a.z.discovery.ZkServerAdvertiser - Connecting to ZooKeeper at zoo:2181 09:03:13.885 INFO o.a.c.f.imps.CuratorFrameworkImpl - Starting 09:03:13.900 INFO org.apache.zookeeper.ZooKeeper - Client environment:zookeeper.version=3.4.8--1, built on 02/06/2016 03:18 GMT 09:03:13.902 INFO org.apache.zookeeper.ZooKeeper - Client environment:host.name=middleware-7b9f7468c8-hvwdg 09:03:13.902 INFO org.apache.zookeeper.ZooKeeper - Client environment:java.version=11.0.7 09:03:13.903 INFO org.apache.zookeeper.ZooKeeper - Client environment:java.vendor=Oracle Corporation 09:03:13.904 INFO org.apache.zookeeper.ZooKeeper - Client environment:java.home=/usr/local/openjdk-11 09:03:13.907 INFO org.apache.zookeeper.ZooKeeper - Client environment:java.class.path=/opt/primaries/app-core.jar 09:03:13.908 INFO org.apache.zookeeper.ZooKeeper - Client environment:java.library.path=/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib 09:03:13.909 INFO org.apache.zookeeper.ZooKeeper - Client environment:java.io.tmpdir=/tmp 09:03:13.909 INFO org.apache.zookeeper.ZooKeeper - Client environment:java.compiler= 09:03:13.910 INFO org.apache.zookeeper.ZooKeeper - Client environment:os.name=Linux 09:03:13.910 INFO org.apache.zookeeper.ZooKeeper - Client environment:os.arch=amd64 09:03:13.911 INFO org.apache.zookeeper.ZooKeeper - Client environment:os.version=4.19.107 09:03:13.911 INFO org.apache.zookeeper.ZooKeeper - Client environment:user.name=root 09:03:13.913 INFO org.apache.zookeeper.ZooKeeper - Client environment:user.home=/root 09:03:13.914 INFO org.apache.zookeeper.ZooKeeper - Client environment:user.dir=/ 09:03:13.915 INFO org.apache.zookeeper.ZooKeeper - Initiating client connection, connectString=zoo:2181 sessionTimeout=60000 watcher=org.apache.curator.ConnectionState@63e98fde 09:03:13.972 INFO org.apache.zookeeper.ClientCnxn - Opening socket connection to server zoo.default.svc.cluster.local/10.109.87.115:2181. Will not attempt to authenticate using SASL (unknown error) 09:03:14.004 INFO org.apache.zookeeper.ClientCnxn - Socket connection established to zoo.default.svc.cluster.local/10.109.87.115:2181, initiating session 09:03:14.018 INFO org.apache.zookeeper.ClientCnxn - Session establishment complete on server zoo.default.svc.cluster.local/10.109.87.115:2181, sessionid = 0x1000003c9840001, negotiated timeout = 40000 09:03:14.023 INFO o.a.c.f.state.ConnectionStateManager - State change: CONNECTED 09:03:14.060 INFO c.h.c.c.s.AbstractWebAppContextLoader - AppContext started 09:03:14.076 INFO o.e.j.s.h.ContextHandler.app_core - Initializing Spring WebSocketDispatcherServlet 'cubaglevt_ws_servlet' 09:03:14.081 INFO c.h.a.g.c.WebSocketDispatcherServlet - Initializing Servlet 'cubaglevt_ws_servlet' 09:03:14.231 WARN c.h.cuba.core.jmx.StatisticsCounter - Сonnection pool not found for statistics counting 09:03:14.893 INFO c.h.a.g.c.WebSocketDispatcherServlet - Completed initialization in 812 ms 09:03:14.907 INFO o.e.j.s.h.ContextHandler.app_core - Initializing Spring RemotingServlet 'remoting' 09:03:14.907 INFO c.h.c.c.sys.remoting.RemotingServlet - Initializing Servlet 'remoting' 09:03:14.966 INFO c.h.c.c.s.r.RemoteServicesBeanCreator - Configuring remote services 09:03:15.399 INFO c.h.c.c.sys.remoting.RemotingServlet - Completed initialization in 491 ms 09:03:15.400 INFO o.e.j.server.handler.ContextHandler - Started o.e.j.w.WebAppContext@6e01f9b0{/app-core,jar:file:/opt/primaries/app-core.jar!/LIB-INF/app-core,AVAILABLE} 09:03:15.415 INFO o.e.jetty.server.AbstractConnector - Started ServerConnector@68267da0{HTTP/1.1,[http/1.1]}{0.0.0.0:8079} 09:03:15.417 INFO org.eclipse.jetty.server.Server - Started @19243ms 09:04:21.080 INFO c.h.a.g.core.WebSocketServer - Opened session WebSocketServerSockJsSession[id=79babfdb55654a2bb6d1708bf50b110a] 09:06:54.142 INFO c.h.cuba.core.app.ClusterManager - New cluster view: [middleware-7b9f7468c8-hvwdg-16637|1] (2) [middleware-7b9f7468c8-hvwdg-16637, middleware-7b9f7468c8-vvqb9-45647] 09:07:15.727 INFO c.h.cuba.core.app.ClusterManager - Suspected member: middleware-7b9f7468c8-vvqb9-45647 09:07:15.732 INFO c.h.cuba.core.app.ClusterManager - New cluster view: [middleware-7b9f7468c8-hvwdg-16637|2] (1) [middleware-7b9f7468c8-hvwdg-16637] 09:08:01.787 INFO c.h.c.s.a.r.PredefinedRoleDefinitionRepository- Initializing predefined role definitions 09:08:01.907 INFO c.h.c.s.a.AuthenticationManagerBean - Logged in: 273f4267-033f-f14a-e4d1-fd089f0ff0ca [admin] 09:08:03.803 INFO c.h.c.c.sys.AbstractViewRepository - Initializing views 09:08:03.863 INFO c.h.c.s.a.AuthenticationManagerBean - Logged in: f5c7640c-7b26-c0cd-8891-7b40a0c00ed1 [admin] 09:08:08.980 INFO c.h.c.s.a.AuthenticationManagerBean - Logged out: 273f4267-033f-f14a-e4d1-fd089f0ff0ca [admin] 09:08:10.366 INFO c.h.cuba.core.app.ClusterManager - New cluster view: [middleware-7b9f7468c8-hvwdg-16637|3] (2) [middleware-7b9f7468c8-hvwdg-16637, middleware-7b9f7468c8-k9h44-23195] 09:08:10.545 INFO c.h.cuba.core.app.ClusterManager - New cluster view: [middleware-7b9f7468c8-hvwdg-16637|4] (3) [middleware-7b9f7468c8-hvwdg-16637, middleware-7b9f7468c8-k9h44-23195, middleware-7b9f7468c8-l2nws-38455] 09:08:53.849 INFO c.h.a.g.core.WebSocketServer - Opened session WebSocketServerSockJsSession[id=224750c5fff34ed2ab7e56f6924aff7d] 09:08:54.884 INFO c.h.cuba.core.sys.ServiceInterceptor - Exception in ConfigStorageService.getDbProperties(): com.haulmont.cuba.security.global.NoUserSessionException: User session not found: 5d88da28-10f6-92d5-3b08-8efd61b5ef40 09:09:01.982 INFO c.h.cuba.core.sys.ServiceInterceptor - Exception in ConfigStorageService.getDbProperties(): com.haulmont.cuba.security.global.NoUserSessionException: User session not found: 5d88da28-10f6-92d5-3b08-8efd61b5ef40