Project

General

Profile

Bug #19034 » webapp_err.log

Elaad FURREEDAN, 2021-03-16 15:34

 
2021-03-16 10:58:35.049:INFO:oejs.SetUIDListener:main: Setting umask=07
2021-03-16 10:58:35.054:INFO:oejs.Server:main: jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_65-b17
2021-03-16 10:58:35.087:INFO:oejdp.ScanningAppProvider:main: Deployment monitor [file:///opt/rudder/share/webapps/] at interval 0
2021-03-16 10:58:36.207:INFO:oejw.StandardDescriptorProcessor:main: NO JSP Support for /endpoint, did not find org.eclipse.jetty.jsp.JettyJspServlet
Use configuration file defined by JVM property -Dlogback.configurationFile : /opt/rudder/etc/logback.xml
2021-03-16 10:58:36.939:INFO:oejs.session:main: DefaultSessionIdManager workerName=node0
2021-03-16 10:58:36.939:INFO:oejs.session:main: No SessionScavenger set, using defaults
2021-03-16 10:58:36.942:INFO:oejs.session:main: node0 Scavenging every 660000ms
2021-03-16 10:58:37.983:INFO:oejshC.endpoint:main: Initializing Spring FrameworkServlet 'dispatcher'
[2021-03-16 10:58:39] WARN org.springframework.context.annotation.ConfigurationClassEnhancer - @Bean method PropertyPlaceholderConfig.propertyConfigurer is non-static and returns an object assignable to Spring's BeanFactoryPostProcessor interface. This will result in a failure to process annotations such as @Autowired, @Resource and @PostConstruct within the method's declaring @Configuration class. Add the 'static' modifier to this method to avoid these container lifecycle issues; see @Bean javadoc for complete details.
[2021-03-16 10:58:39] INFO com.normation.inventory.provisioning.endpoint.config.PropertyPlaceholderConfig - Use configuration file defined by JVM property -Dinventoryweb.configFile : /opt/rudder/etc/inventory-web.properties
[2021-03-16 10:58:41] INFO inventory-processing - Incoming inventory watcher started - process existing inventories
2021-03-16 10:58:42.011:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@6c3708b3{/endpoint,file:///var/rudder/tmp/jetty/jetty-endpoint.war.dir/webapp/,AVAILABLE}{/endpoint.war}
2021-03-16 10:58:44.574:INFO:oejw.StandardDescriptorProcessor:main: NO JSP Support for /rudder, did not find org.eclipse.jetty.jsp.JettyJspServlet
Use configuration file defined by JVM property -Dlogback.configurationFile : /opt/rudder/etc/logback.xml
[2021-03-16 10:58:45] INFO application - Use configuration file defined by JVM property -Drudder.configFile : /opt/rudder/etc/rudder-web.properties
[2021-03-16 10:58:45] INFO application - Global exception handler configured to stop Rudder on: java.lang.Error
[2021-03-16 10:58:46] INFO application - Property 'rudder.batch.delete.software.interval' is missing or empty in rudder.configFile. Default to 24 hours.
[2021-03-16 10:58:46] INFO application - Starting Rudder 5.0.17 web application [build timestamp: 2020-04-20T22:13:36Z]
[2021-03-16 10:58:56] INFO application - Starting [Store Agent Run Times] scheduler with a period of 5 s
[2021-03-16 10:58:56] INFO application - List of registered properties:
[2021-03-16 10:58:57] INFO application - registered property: awt.toolkit="sun.awt.X11.XToolkit"
[2021-03-16 10:58:57] INFO application - registered property: bin.emergency.stop="/opt/rudder/bin/cfe-red-button.sh"
[2021-03-16 10:58:57] INFO application - registered property: file.encoding.pkg="sun.io"
[2021-03-16 10:58:57] INFO application - registered property: file.separator="/"
[2021-03-16 10:58:57] INFO application - registered property: history.inventories.rootdir="/var/rudder/inventories/historical"
[2021-03-16 10:58:57] INFO application - registered property: inventoryweb.configFile="/opt/rudder/etc/inventory-web.properties"
[2021-03-16 10:58:57] INFO application - registered property: java.awt.graphicsenv="sun.awt.X11GraphicsEnvironment"
[2021-03-16 10:58:57] INFO application - registered property: java.awt.headless="true"
[2021-03-16 10:58:57] INFO application - registered property: java.awt.printerjob="sun.print.PSPrinterJob"
[2021-03-16 10:58:57] INFO application - registered property: java.class.path="/opt/rudder/jetty/start.jar"
[2021-03-16 10:58:57] INFO application - registered property: java.class.version="52.0"
[2021-03-16 10:58:57] INFO application - registered property: java.endorsed.dirs="/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/endorsed"
[2021-03-16 10:58:57] INFO application - registered property: java.ext.dirs="/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/ext:/usr/java/packages/lib/ext"
[2021-03-16 10:58:57] INFO application - registered property: java.home="/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre"
[2021-03-16 10:58:57] INFO application - registered property: java.io.tmpdir="/var/rudder/tmp/jetty"
[2021-03-16 10:58:57] INFO application - registered property: java.library.path="/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib"
[2021-03-16 10:58:57] INFO application - registered property: java.runtime.name="OpenJDK Runtime Environment"
[2021-03-16 10:58:57] INFO application - registered property: java.runtime.version="1.8.0_65-b17"
[2021-03-16 10:58:57] INFO application - registered property: java.specification.name="Java Platform API Specification"
[2021-03-16 10:58:57] INFO application - registered property: java.specification.vendor="Oracle Corporation"
[2021-03-16 10:58:57] INFO application - registered property: java.specification.version="1.8"
[2021-03-16 10:58:57] INFO application - registered property: java.vendor.url.bug="http://bugreport.sun.com/bugreport/"
[2021-03-16 10:58:57] INFO application - registered property: java.version="1.8.0_65"
[2021-03-16 10:58:57] INFO application - registered property: java.vm.info="mixed mode"
[2021-03-16 10:58:57] INFO application - registered property: java.vm.name="OpenJDK 64-Bit Server VM"
[2021-03-16 10:58:57] INFO application - registered property: java.vm.specification.name="Java Virtual Machine Specification"
[2021-03-16 10:58:57] INFO application - registered property: java.vm.specification.vendor="Oracle Corporation"
[2021-03-16 10:58:57] INFO application - registered property: java.vm.specification.version="1.8"
[2021-03-16 10:58:57] INFO application - registered property: java.vm.vendor="Oracle Corporation"
[2021-03-16 10:58:57] INFO application - registered property: java.vm.version="25.65-b01"
[2021-03-16 10:58:57] INFO application - registered property: jetty.base="/opt/rudder/etc/rudder-jetty-base"
[2021-03-16 10:58:57] INFO application - registered property: jetty.git.hash="d5fc0523cfa96bfebfbda19606cad384d772f04c"
[2021-03-16 10:58:57] INFO application - registered property: jetty.home="/opt/rudder/jetty"
[2021-03-16 10:58:57] INFO application - registered property: jetty.tag.version="master"
[2021-03-16 10:58:57] INFO application - registered property: jetty.version="9.4.11.v20180605"
[2021-03-16 10:58:57] INFO application - registered property: ldap.authdn="cn=manager,cn=rudder-configuration"
[2021-03-16 10:58:57] INFO application - registered property: ldap.authpw=**********
[2021-03-16 10:58:57] INFO application - registered property: ldap.host="localhost"
[2021-03-16 10:58:57] INFO application - registered property: ldap.inventories.accepted.basedn="ou=Accepted Inventories, ou=Inventories, cn=rudder-configuration"
[2021-03-16 10:58:57] INFO application - registered property: ldap.inventories.pending.basedn="ou=Pending Inventories, ou=Inventories, cn=rudder-configuration"
[2021-03-16 10:58:57] INFO application - registered property: ldap.inventories.removed.basedn="ou=Removed Inventories, ou=Inventories, cn=rudder-configuration"
[2021-03-16 10:58:57] INFO application - registered property: ldap.inventories.software.basedn="ou=Inventories, cn=rudder-configuration"
[2021-03-16 10:58:57] INFO application - registered property: ldap.maxPoolSize="2"
[2021-03-16 10:58:57] INFO application - registered property: ldap.node.base="cn=rudder-configuration"
[2021-03-16 10:58:57] INFO application - registered property: ldap.port="389"
[2021-03-16 10:58:57] INFO application - registered property: ldap.rudder.base="ou=Rudder, cn=rudder-configuration"
[2021-03-16 10:58:57] INFO application - registered property: line.separator="\n"
[2021-03-16 10:58:57] INFO application - registered property: logback.configurationFile="/opt/rudder/etc/logback.xml"
[2021-03-16 10:58:57] INFO application - registered property: os.arch="amd64"
[2021-03-16 10:58:57] INFO application - registered property: os.name="Linux"
[2021-03-16 10:58:57] INFO application - registered property: os.version="3.12.49-11-default"
[2021-03-16 10:58:57] INFO application - registered property: path.separator=":"
[2021-03-16 10:58:57] INFO application - registered property: rudder.auth.provider="file"
[2021-03-16 10:58:57] INFO application - registered property: rudder.authFile="/opt/rudder/etc/rudder-users.xml"
[2021-03-16 10:58:57] INFO application - registered property: rudder.autoArchiveItems="true"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.databasecleaner.runtime.day="sunday"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.databasecleaner.runtime.hour="0"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.databasecleaner.runtime.minute="0"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.dyngroup.updateInterval="5"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.purge.inventories.delete.TTL="7"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.purge.inventories.delete.interval="24"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.reports.logInterval="1"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.reportsCleaner.deleteLogReport.TTL="2x"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.reportscleaner.archive.TTL="4"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.reportscleaner.compliancelevels.delete.TTL="15"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.reportscleaner.delete.TTL="15"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.reportscleaner.frequency="daily"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.storeAgentRunTimes.maxBatchSize="5"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.storeAgentRunTimes.maxDays="0"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.storeAgentRunTimes.maxMinutes="30"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.storeAgentRunTimes.updateInterval="5"
[2021-03-16 10:58:57] INFO application - registered property: rudder.batch.techniqueLibrary.updateInterval="5"
[2021-03-16 10:58:57] INFO application - registered property: rudder.community.port="5309"
[2021-03-16 10:58:57] INFO application - registered property: rudder.configFile="/opt/rudder/etc/rudder-web.properties"
[2021-03-16 10:58:57] INFO application - registered property: rudder.debug.nodeconfiguration.path="/var/log/rudder/nodeConfigurations"
[2021-03-16 10:58:57] INFO application - registered property: rudder.dir.backup="/var/rudder/share/backup/"
[2021-03-16 10:58:57] INFO application - registered property: rudder.dir.dependencies="/var/rudder/tools"
[2021-03-16 10:58:57] INFO application - registered property: rudder.dir.gitRoot="/var/rudder/configuration-repository"
[2021-03-16 10:58:57] INFO application - registered property: rudder.dir.lock="/var/rudder/lock/"
[2021-03-16 10:58:57] INFO application - registered property: rudder.dir.shared.files.folder="/var/rudder/configuration-repository/shared-files"
[2021-03-16 10:58:57] INFO application - registered property: rudder.dir.techniques="/var/rudder/configuration-repository/techniques"
[2021-03-16 10:58:57] INFO application - registered property: rudder.dir.uploaded.file.sharing="/var/rudder/files/"
[2021-03-16 10:58:57] INFO application - registered property: rudder.endpoint.cmdb="http://localhost:8080/endpoint/upload/"
[2021-03-16 10:58:57] INFO application - registered property: rudder.hooks.ignore-suffixes=".swp, ~, .bak, .cfnew , .cfsaved , .cfedited, .cfdisabled, .cfmoved,.dpkg-old, .dpkg-dist, .dpkg-new, .dpkg-tmp,.disable , .disabled , _disable , _disabled,.ucf-old , .ucf-dist , .ucf-new ,.rpmnew , .rpmsave , .rpmorig"
[2021-03-16 10:58:57] INFO application - registered property: rudder.jdbc.batch.max.size="500"
[2021-03-16 10:58:57] INFO application - registered property: rudder.jdbc.driver="org.postgresql.Driver"
[2021-03-16 10:58:57] INFO application - registered property: rudder.jdbc.maxPoolSize="25"
[2021-03-16 10:58:57] INFO application - registered property: rudder.jdbc.password=**********
[2021-03-16 10:58:57] INFO application - registered property: rudder.jdbc.url="jdbc:postgresql://localhost:5432/rudder"
[2021-03-16 10:58:57] INFO application - registered property: rudder.jdbc.username="rudder"
[2021-03-16 10:58:57] INFO application - registered property: rudder.jvm.fatal.exceptions=""
[2021-03-16 10:58:57] INFO application - registered property: rudder.rest.allowNonAuthenticatedUser="true"
[2021-03-16 10:58:57] INFO application - registered property: rudder.server-roles.cfengine-mission-portal="autodetect"
[2021-03-16 10:58:57] INFO application - registered property: rudder.server-roles.db="autodetect"
[2021-03-16 10:58:57] INFO application - registered property: rudder.server-roles.inventory-endpoint="autodetect"
[2021-03-16 10:58:57] INFO application - registered property: rudder.server-roles.ldap="autodetect"
[2021-03-16 10:58:57] INFO application - registered property: rudder.server-roles.relay-promises-only="autodetect"
[2021-03-16 10:58:57] INFO application - registered property: rudder.server-roles.relay-top="autodetect"
[2021-03-16 10:58:57] INFO application - registered property: rudder.server-roles.web="autodetect"
[2021-03-16 10:58:57] INFO application - registered property: rudder.server.relay.api="https://localhost/rudder/relay-api"
[2021-03-16 10:58:57] INFO application - registered property: rudder.syslog.port="514"
[2021-03-16 10:58:57] INFO application - registered property: rudder.techniqueLibrary.git.refs.path="refs/heads/master"
[2021-03-16 10:58:57] INFO application - registered property: rudder.webdav.password=**********
[2021-03-16 10:58:57] INFO application - registered property: rudder.webdav.user="rudder"
[2021-03-16 10:58:57] INFO application - registered property: run.mode="production"
[2021-03-16 10:58:57] INFO application - registered property: sun.arch.data.model="64"
[2021-03-16 10:58:57] INFO application - registered property: sun.boot.class.path="/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/resources.jar:/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/rt.jar:/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/sunrsasign.jar:/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/jsse.jar:/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/jce.jar:/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/charsets.jar:/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/jfr.jar:/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/classes"
[2021-03-16 10:58:57] INFO application - registered property: sun.boot.library.path="/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/amd64"
[2021-03-16 10:58:57] INFO application - registered property: sun.cpu.endian="little"
[2021-03-16 10:58:57] INFO application - registered property: sun.cpu.isalist=""
[2021-03-16 10:58:57] INFO application - registered property: sun.io.unicode.encoding="UnicodeLittle"
[2021-03-16 10:58:57] INFO application - registered property: sun.java.command="/opt/rudder/jetty/start.jar OPTIONS=Server jetty.state=/opt/rudder/etc/rudder-jetty-base/rudder-jetty.state jetty-started.xml"
[2021-03-16 10:58:57] INFO application - registered property: sun.java.launcher="SUN_STANDARD"
[2021-03-16 10:58:57] INFO application - registered property: sun.jnu.encoding="ANSI_X3.4-1968"
[2021-03-16 10:58:57] INFO application - registered property: sun.management.compiler="HotSpot 64-Bit Tiered Compilers"
[2021-03-16 10:58:57] INFO application - registered property: sun.os.patch.level="unknown"
[2021-03-16 10:58:57] INFO application - registered property: upload.root.directory="/var/rudder/files/"
[2021-03-16 10:58:57] INFO application - registered property: user.country="US"
[2021-03-16 10:58:57] INFO application - registered property: user.dir="/opt/rudder/etc/rudder-jetty-base"
[2021-03-16 10:58:57] INFO application - registered property: user.home="/root"
[2021-03-16 10:58:57] INFO application - registered property: user.language="en"
[2021-03-16 10:58:57] INFO application - registered property: user.name="root"
[2021-03-16 10:58:57] INFO application - registered property: user.timezone="Europe/Paris"
[2021-03-16 10:58:57] INFO application - Plugin's license directory: '/opt/rudder/etc/plugins/licenses/'
[2021-03-16 10:58:57] INFO bootchecks - Check LDAP and PostgreSQL connection
[2021-03-16 10:58:57] INFO bootchecks - LDAP and PostgreSQL connection are OK
[2021-03-16 10:58:57] INFO bootchecks - Check mandatory DIT entries
[2021-03-16 10:58:57] INFO bootchecks - All the required DIT entries are present in the LDAP directory
[2021-03-16 10:58:57] INFO bootchecks - Check initialization of User Technique Library
[2021-03-16 10:58:57] INFO bootchecks - Check event log migration format 5 -> 6
[2021-03-16 10:58:57] INFO bootchecks - Check existence of at least one archive of the configuration
[2021-03-16 10:58:57] INFO bootchecks - First full archive of configuration-repository items done
[2021-03-16 10:58:57] INFO bootchecks - Check rules archive directory in configuration-repository
[2021-03-16 10:58:57] INFO bootchecks - Creating directory '/var/rudder/configuration-repository/ruleCategories' exists, done
[2021-03-16 10:58:57] INFO migration - No migration detected by migration script (table 'migrationeventlog' is empty or does not exist)
[2021-03-16 10:58:57] INFO bootchecks - Check that directive variables use the namespace 'rudder'
[2021-03-16 10:58:58] INFO migration - Migration of inline variables in Directives to 'rudder' namespace already done, skipping
[2021-03-16 10:58:58] INFO bootchecks - Check for force reload of Techniques library
[2021-03-16 10:58:58] INFO bootchecks - Flag file '/opt/rudder/etc/force_technique_reload' does not exist, do not Technique library will not be reloaded
[2021-03-16 10:58:58] INFO bootchecks - Check that system group / directive / rules for Rudder 4.2 are agent-specific
[2021-03-16 10:58:58] INFO bootchecks - Regenerate all ncf techniques
[2021-03-16 10:58:58] INFO bootchecks - Resume policy update if it was running before shutdown
[2021-03-16 10:58:58] INFO bootchecks - Flag file '/opt/rudder/etc/policy-update-running' does not exist, No need to start a new policy update
[2021-03-16 10:58:58] INFO bootchecks - Trigger policy update if it was requested during shutdown
[2021-03-16 10:58:58] INFO bootchecks - Create system api token
[2021-03-16 10:58:58] INFO bootchecks - System api token file created in /var/rudder/run/api-token
[2021-03-16 10:58:58] INFO bootchecks - Update existing API token to 'RW' autorization level.
[2021-03-16 10:58:58] INFO bootchecks - Check NashHorn JS engine for warning
2021-03-16 10:58:58.715:INFO:oejshC.rudder:main: Initializing Spring root WebApplicationContext
[2021-03-16 10:59:01] INFO application - Configured authentication provider(s): [rootAdmin, file]
[2021-03-16 10:59:01] INFO application - No master admin account is defined. You can define one with 'rudder.auth.admin.login' and 'rudder.auth.admin.password' properties in the configuration file
[2021-03-16 10:59:01] INFO application - Add backend providers 'Default authentication backends provider: 'file','rootAdmin'
[2021-03-16 10:59:01] INFO application - Using configuration file defined by JVM property -Drudder.authFile : /opt/rudder/etc/rudder-users.xml
[2021-03-16 10:59:02] INFO bootstrap.liftweb.Boot - classpath
[2021-03-16 10:59:03] INFO org.reflections.Reflections - Reflections took 202 ms to scan 121 urls, producing 0 keys and 0 values
[2021-03-16 10:59:04] INFO application - Application Rudder started
2021-03-16 10:59:04.075:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@3eba57a7{/rudder,file:///var/rudder/tmp/jetty/jetty-rudder.war.dir/webapp/,AVAILABLE}{/rudder.war}
2021-03-16 10:59:04.106:INFO:oejs.AbstractConnector:main: Started ServerConnector@37d2ded9{HTTP/1.1,[http/1.1]}{127.0.0.1:8080}
2021-03-16 10:59:04.106:INFO:oejs.Server:main: Started @30448ms
[2021-03-16 10:59:10] INFO bootchecks - All ncf techniques were updated
[2021-03-16 11:00:32] INFO inventory-processing - Watch new inventory file 'server-root.ocs' with signature available: process.
[2021-03-16 11:00:33] WARN com.normation.inventory.provisioning.fusion.FusionReportUnmarshaller - Error when parsing date for last user loggin. Awaited format is EEE MMM dd HH:mm, found: Dec 11 11:47 -
[2021-03-16 11:00:44] INFO inventory-processing - Report 'server-root.ocs' for node 'server.rudder.local' [root] (signature:certified) processed in 8 seconds and 350 milliseconds ms
[2021-03-16 11:00:55] INFO scheduledJob - Update in node inventories main information detected: triggering a policy generation
[2021-03-16 11:00:55] INFO policy.generation - Start policy generation, checking updated rules
[2021-03-16 11:00:56] INFO policy.generation - [metrics] Xmx:1015 MB nodes:1 (cached:1) rules:5 (enabled:4) techniques:44 (enabled:44) directives:4 (enabled:4) groups:2 (dynamic:2) parameters:1
[2021-03-16 11:00:59] INFO policy.generation - Configuration of following 1 nodes were updated, their policies are going to be written: [root]
[2021-03-16 11:01:02] INFO inventory-processing - Watch new inventory file 'relay-9ddab0eb-ba7f-499b-a43d-4c99692aec87.ocs' with signature available: process.
[2021-03-16 11:01:02] WARN com.normation.inventory.provisioning.fusion.FusionReportUnmarshaller - Error when parsing date for last user loggin. Awaited format is EEE MMM dd HH:mm, found: Dec 11 11:47 -
[2021-03-16 11:01:04] INFO inventory-processing - Report 'relay-9ddab0eb-ba7f-499b-a43d-4c99692aec87.ocs' for node 'relay.rudder.local' [9ddab0eb-ba7f-499b-a43d-4c99692aec87] (signature:certified) processed in 1 second and 3 milliseconds ms
[2021-03-16 11:01:04] INFO policy.generation - Timing summary:
[2021-03-16 11:01:04] INFO policy.generation - Run pre-gen scripts hooks : 58 ms
[2021-03-16 11:01:04] INFO policy.generation - Run pre-gen modules hooks : 16 ms
[2021-03-16 11:01:04] INFO policy.generation - Fetch all information : 468 ms
[2021-03-16 11:01:04] INFO policy.generation - Historize names : 494 ms
[2021-03-16 11:01:04] INFO policy.generation - Build current rule values : 103 ms
[2021-03-16 11:01:04] INFO policy.generation - Build target configuration : 1556 ms
[2021-03-16 11:01:04] INFO policy.generation - Write node configurations : 4370 ms
[2021-03-16 11:01:04] INFO policy.generation - Save expected reports : 99 ms
[2021-03-16 11:01:04] INFO policy.generation - Run post generation hooks : 119 ms
[2021-03-16 11:01:04] INFO policy.generation - Number of nodes updated : 1
[2021-03-16 11:01:04] INFO policy.generation - Policy generation completed in: 8 s
[2021-03-16 11:01:04] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed
[2021-03-16 11:01:04] INFO policy.generation - Successful policy update '3' [started 2021-03-16 11:00:55 - ended 2021-03-16 11:01:04]
[2021-03-16 11:01:41] INFO hooks - Executing post-node-acceptance hooks for node with id '9ddab0eb-ba7f-499b-a43d-4c99692aec87'
[2021-03-16 11:01:41] INFO scheduledJob - Dynamic group all-nodes-with-cfengine-agent: added node with id: [ 9ddab0eb-ba7f-499b-a43d-4c99692aec87 ], removed: nothing
[2021-03-16 11:01:41] INFO scheduledJob - Dynamic group hasPolicyServer-root: added node with id: [ 9ddab0eb-ba7f-499b-a43d-4c99692aec87 ], removed: nothing
[2021-03-16 11:01:41] INFO policy.generation - Start policy generation, checking updated rules
[2021-03-16 11:01:41] INFO policy.generation - [metrics] Xmx:1015 MB nodes:2 (cached:1) rules:5 (enabled:4) techniques:44 (enabled:44) directives:4 (enabled:4) groups:2 (dynamic:2) parameters:1
[2021-03-16 11:01:41] INFO policy.generation - Configuration of following 2 nodes were updated, their policies are going to be written: [9ddab0eb-ba7f-499b-a43d-4c99692aec87, root]
[2021-03-16 11:01:46] INFO policy.generation - Timing summary:
[2021-03-16 11:01:46] INFO policy.generation - Run pre-gen scripts hooks : 1 ms
[2021-03-16 11:01:46] INFO policy.generation - Run pre-gen modules hooks : 0 ms
[2021-03-16 11:01:46] INFO policy.generation - Fetch all information : 112 ms
[2021-03-16 11:01:46] INFO policy.generation - Historize names : 118 ms
[2021-03-16 11:01:46] INFO policy.generation - Build current rule values : 2 ms
[2021-03-16 11:01:46] INFO policy.generation - Build target configuration : 132 ms
[2021-03-16 11:01:46] INFO policy.generation - Write node configurations : 4395 ms
[2021-03-16 11:01:46] INFO policy.generation - Save expected reports : 2 ms
[2021-03-16 11:01:46] INFO policy.generation - Run post generation hooks : 21 ms
[2021-03-16 11:01:46] INFO policy.generation - Number of nodes updated : 2
[2021-03-16 11:01:46] INFO policy.generation - Policy generation completed in: 4 s
[2021-03-16 11:01:46] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed
[2021-03-16 11:01:46] INFO policy.generation - Successful policy update '4' [started 2021-03-16 11:01:41 - ended 2021-03-16 11:01:46]
[2021-03-16 11:01:54] INFO policy.generation - Start policy generation, checking updated rules
[2021-03-16 11:01:55] INFO policy.generation - [metrics] Xmx:1015 MB nodes:2 (cached:2) rules:7 (enabled:6) techniques:44 (enabled:44) directives:6 (enabled:6) groups:3 (dynamic:3) parameters:1
[2021-03-16 11:01:55] INFO policy.generation - Configuration of following 1 nodes were updated, their policies are going to be written: [9ddab0eb-ba7f-499b-a43d-4c99692aec87]
[2021-03-16 11:01:56] ERROR com.normation.rudder.services.policies.ParallelSequence - Failure in boxToEither: Error when calculating configuration for node 'relay.rudder.local' (9ddab0eb-ba7f-499b-a43d-4c99692aec87) <- Error for node '9ddab0eb-ba7f-499b-a43d-4c99692aec87' bundle creation <- We could not generate policies for server '9ddab0eb-ba7f-499b-a43d-4c99692aec87', therefore making updates for nodes behind it unavailable. Maybe you are missing 'scale out' plugin?
[2021-03-16 11:01:56] INFO policy.generation - Policy generation completed in: 1 s
[2021-03-16 11:01:56] ERROR policy.generation - Error when updating policy, reason was: Cannot write nodes configuration <- Error when calculating configuration for node 'relay.rudder.local' (9ddab0eb-ba7f-499b-a43d-4c99692aec87) <- Error for node '9ddab0eb-ba7f-499b-a43d-4c99692aec87' bundle creation <- We could not generate policies for server '9ddab0eb-ba7f-499b-a43d-4c99692aec87', therefore making updates for nodes behind it unavailable. Maybe you are missing 'scale out' plugin?
[2021-03-16 11:01:56] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed
[2021-03-16 11:01:56] ERROR policy.generation - Policy update error for process '5' at 2021-03-16 11:01:56: Cannot write nodes configuration
[2021-03-16 11:02:01] INFO policy.generation - Start policy generation, checking updated rules
[2021-03-16 11:02:01] INFO policy.generation - [metrics] Xmx:1015 MB nodes:2 (cached:2) rules:7 (enabled:6) techniques:44 (enabled:44) directives:6 (enabled:6) groups:3 (dynamic:3) parameters:1
[2021-03-16 11:02:01] INFO policy.generation - Configuration of following 1 nodes were updated, their policies are going to be written: [9ddab0eb-ba7f-499b-a43d-4c99692aec87]
[2021-03-16 11:02:02] ERROR com.normation.rudder.services.policies.ParallelSequence - Failure in boxToEither: Error when calculating configuration for node 'relay.rudder.local' (9ddab0eb-ba7f-499b-a43d-4c99692aec87) <- Error for node '9ddab0eb-ba7f-499b-a43d-4c99692aec87' bundle creation <- We could not generate policies for server '9ddab0eb-ba7f-499b-a43d-4c99692aec87', therefore making updates for nodes behind it unavailable. Maybe you are missing 'scale out' plugin?
[2021-03-16 11:02:02] INFO policy.generation - Policy generation completed in:
[2021-03-16 11:02:02] ERROR policy.generation - Error when updating policy, reason was: Cannot write nodes configuration <- Error when calculating configuration for node 'relay.rudder.local' (9ddab0eb-ba7f-499b-a43d-4c99692aec87) <- Error for node '9ddab0eb-ba7f-499b-a43d-4c99692aec87' bundle creation <- We could not generate policies for server '9ddab0eb-ba7f-499b-a43d-4c99692aec87', therefore making updates for nodes behind it unavailable. Maybe you are missing 'scale out' plugin?
[2021-03-16 11:02:02] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed
[2021-03-16 11:02:02] ERROR policy.generation - Policy update error for process '6' at 2021-03-16 11:02:02: Cannot write nodes configuration
[2021-03-16 11:05:57] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:04] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:11] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:19] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:26] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:31] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:35] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:40] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:45] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:49] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:06:54] ERROR com.normation.rudder.rest.RestUtils - "The node with id 88af1763-d20b-4f03-b1de-b12bfed7c75a was not found in removed nodes"
[2021-03-16 11:07:00] ERROR com.normation.rudder.rest.RestUtils - "Can not accept not found inventory with id '88af1763-d20b-4f03-b1de-b12bfed7c75a'"
[2021-03-16 11:58:55] INFO scheduledJob - Purged 0 unreferenced softwares
[2021-03-16 13:22:01] INFO compliance - [metrics] global compliance (number of components): undefined
2021-03-16 13:25:58.648:INFO:oejs.AbstractConnector:Thread-11: Stopped ServerConnector@37d2ded9{HTTP/1.1,[http/1.1]}{127.0.0.1:8080}
2021-03-16 13:25:58.648:INFO:oejs.session:Thread-11: node0 Stopped scavenging
2021-03-16 13:25:58.693:INFO:oejshC.rudder:Thread-11: Closing Spring root WebApplicationContext
2021-03-16 13:25:58.833:INFO:oejsh.ContextHandler:Thread-11: Stopped o.e.j.w.WebAppContext@3eba57a7{/rudder,null,UNAVAILABLE}{/rudder.war}
2021-03-16 13:25:58.834:INFO:oejshC.endpoint:Thread-11: Destroying Spring FrameworkServlet 'dispatcher'
2021-03-16 13:25:58.858:INFO:oejsh.ContextHandler:Thread-11: Stopped o.e.j.w.WebAppContext@6c3708b3{/endpoint,null,UNAVAILABLE}{/endpoint.war}
(2-2/2)