Actions
Bug #16520
closedWriteNodeCertificatesPemImpl error after upgrade from 5.0 to 6.0
Pull Request:
Severity:
UX impact:
User visibility:
Effort required:
Priority:
0
Name check:
To do
Fix check:
To do
Regression:
Description
I tried to upgrade a 5.0 server to a 6.0.2 one, upgrade went fine but at the end, the webapp was stuck on boot.
I restarted jetty, and it went up but, the generation was broken, at the beginning due to duplicate copies of bundle, and then the error changed
as described in the log below.
The strange part is that restarting rudder-slapd fixed everything for some reasons...
2020-01-10 11:10:00.499:INFO:oejs.SetUIDListener:main: Setting umask=07 2020-01-10 11:10:00.501:INFO:oejs.Server:main: jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_65-b17 2020-01-10 11:10:00.515:INFO:oejdp.ScanningAppProvider:main: Deployment monitor [file:///opt/rudder/share/webapps/] at interval 1 2020-01-10 11:10:01.662: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 2020-01-10 11:10:02.035:INFO:oejs.session:main: DefaultSessionIdManager workerName=node0 2020-01-10 11:10:02.035:INFO:oejs.session:main: No SessionScavenger set, using defaults 2020-01-10 11:10:02.036:INFO:oejs.session:main: node0 Scavenging every 600000ms [2020-01-10 11:10:02+0100] INFO application - Rudder starts with PID 22396 on 2 cores [2020-01-10 11:10:02+0100] INFO application - Use configuration file defined by JVM property -Drudder.configFile : /opt/rudder/etc/rudder-web.properties [2020-01-10 11:10:02+0100] INFO application - Global exception handler configured to stop Rudder on: java.lang.Error [2020-01-10 11:10:02+0100] INFO application - Property 'rudder.batch.delete.software.interval' is missing or empty in rudder.configFile. Default to 24 hours. [2020-01-10 11:10:02+0100] INFO application - Starting Rudder 6.0.2 web application [build timestamp: 2020-01-08T18:25:33Z] [2020-01-10 11:10:07+0100] INFO application - Starting [Store Agent Run Times] scheduler with a period of 5 s 2020-01-10 11:10:07.716:INFO:oejshC.rudder:main: Initializing Spring root WebApplicationContext [2020-01-10 11:10:07+0100] INFO application - List of registered properties: [2020-01-10 11:10:07+0100] INFO application - registered property: awt.toolkit="sun.awt.X11.XToolkit" [2020-01-10 11:10:07+0100] INFO application - registered property: bin.emergency.stop="/opt/rudder/bin/cfe-red-button.sh" [2020-01-10 11:10:07+0100] INFO application - registered property: file.encoding.pkg="sun.io" [2020-01-10 11:10:07+0100] INFO application - registered property: file.separator="/" [2020-01-10 11:10:07+0100] INFO application - registered property: history.inventories.rootdir="/var/rudder/inventories/historical" [2020-01-10 11:10:07+0100] INFO application - registered property: inventories.root.directory="/var/rudder/inventories" [2020-01-10 11:10:07+0100] INFO application - registered property: inventories.watcher.enable="true" [2020-01-10 11:10:07+0100] INFO application - registered property: inventories.watcher.waitForSignatureDuration="10" [2020-01-10 11:10:07+0100] INFO application - registered property: java.awt.graphicsenv="sun.awt.X11GraphicsEnvironment" [2020-01-10 11:10:07+0100] INFO application - registered property: java.awt.headless="true" [2020-01-10 11:10:07+0100] INFO application - registered property: java.awt.printerjob="sun.print.PSPrinterJob" [2020-01-10 11:10:07+0100] INFO application - registered property: java.class.path="/opt/rudder/jetty/start.jar" [2020-01-10 11:10:07+0100] INFO application - registered property: java.class.version="52.0" [2020-01-10 11:10:07+0100] INFO application - registered property: java.endorsed.dirs="/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/endorsed" [2020-01-10 11:10:07+0100] 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" [2020-01-10 11:10:07+0100] INFO application - registered property: java.home="/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre" [2020-01-10 11:10:07+0100] INFO application - registered property: java.io.tmpdir="/var/rudder/tmp/jetty" [2020-01-10 11:10:07+0100] INFO application - registered property: java.library.path="/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib" [2020-01-10 11:10:07+0100] INFO application - registered property: java.runtime.name="OpenJDK Runtime Environment" [2020-01-10 11:10:07+0100] INFO application - registered property: java.runtime.version="1.8.0_65-b17" [2020-01-10 11:10:07+0100] INFO application - registered property: java.specification.name="Java Platform API Specification" [2020-01-10 11:10:07+0100] INFO application - registered property: java.specification.vendor="Oracle Corporation" [2020-01-10 11:10:07+0100] INFO application - registered property: java.specification.version="1.8" [2020-01-10 11:10:07+0100] INFO application - registered property: java.vendor.url.bug="http://bugreport.sun.com/bugreport/" [2020-01-10 11:10:07+0100] INFO application - registered property: java.version="1.8.0_65" [2020-01-10 11:10:07+0100] INFO application - registered property: java.vm.info="mixed mode" [2020-01-10 11:10:07+0100] INFO application - registered property: java.vm.name="OpenJDK 64-Bit Server VM" [2020-01-10 11:10:07+0100] INFO application - registered property: java.vm.specification.name="Java Virtual Machine Specification" [2020-01-10 11:10:07+0100] INFO application - registered property: java.vm.specification.vendor="Oracle Corporation" [2020-01-10 11:10:07+0100] INFO application - registered property: java.vm.specification.version="1.8" [2020-01-10 11:10:07+0100] INFO application - registered property: java.vm.vendor="Oracle Corporation" [2020-01-10 11:10:07+0100] INFO application - registered property: java.vm.version="25.65-b01" [2020-01-10 11:10:07+0100] INFO application - registered property: jetty.base="/opt/rudder/etc/rudder-jetty-base" [2020-01-10 11:10:07+0100] INFO application - registered property: jetty.git.hash="d5fc0523cfa96bfebfbda19606cad384d772f04c" [2020-01-10 11:10:07+0100] INFO application - registered property: jetty.home="/opt/rudder/jetty" [2020-01-10 11:10:07+0100] INFO application - registered property: jetty.tag.version="master" [2020-01-10 11:10:07+0100] INFO application - registered property: jetty.version="9.4.11.v20180605" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.authdn="cn=manager,cn=rudder-configuration" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.authpw=********** [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.host="localhost" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.inventories.accepted.basedn="ou=Accepted Inventories, ou=Inventories, cn=rudder-configuration" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.inventories.pending.basedn="ou=Pending Inventories, ou=Inventories, cn=rudder-configuration" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.inventories.removed.basedn="ou=Removed Inventories, ou=Inventories, cn=rudder-configuration" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.inventories.software.basedn="ou=Inventories, cn=rudder-configuration" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.maxPoolSize="2" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.node.base="cn=rudder-configuration" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.port="389" [2020-01-10 11:10:07+0100] INFO application - registered property: ldap.rudder.base="ou=Rudder, cn=rudder-configuration" [2020-01-10 11:10:07+0100] INFO application - registered property: line.separator="\n" [2020-01-10 11:10:07+0100] INFO application - registered property: logback.configurationFile="/opt/rudder/etc/logback.xml" [2020-01-10 11:10:07+0100] INFO application - registered property: os.arch="amd64" [2020-01-10 11:10:07+0100] INFO application - registered property: os.name="Linux" [2020-01-10 11:10:07+0100] INFO application - registered property: os.version="3.12.49-11-default" [2020-01-10 11:10:07+0100] INFO application - registered property: path.separator=":" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.auth.provider="file" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.authFile="/opt/rudder/etc/rudder-users.xml" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.autoArchiveItems="true" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.databasecleaner.runtime.day="sunday" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.databasecleaner.runtime.hour="0" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.databasecleaner.runtime.minute="0" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.dyngroup.updateInterval="5" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.purge.inventories.delete.TTL="7" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.purge.inventories.delete.interval="24" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.reports.logInterval="1" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.reportsCleaner.deleteLogReport.TTL="2x" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.reportscleaner.archive.TTL="4" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.reportscleaner.compliancelevels.delete.TTL="15" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.reportscleaner.delete.TTL="15" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.reportscleaner.frequency="daily" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.storeAgentRunTimes.maxBatchSize="5" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.storeAgentRunTimes.maxDays="0" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.storeAgentRunTimes.maxMinutes="30" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.storeAgentRunTimes.updateInterval="5" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.batch.techniqueLibrary.updateInterval="5" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.community.port="5309" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.configFile="/opt/rudder/etc/rudder-web.properties" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.debug.nodeconfiguration.path="/var/log/rudder/nodeConfigurations" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.dir.backup="/var/rudder/share/backup/" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.dir.dependencies="/var/rudder/tools" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.dir.gitRoot="/var/rudder/configuration-repository" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.dir.lock="/var/rudder/lock/" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.dir.shared.files.folder="/var/rudder/configuration-repository/shared-files" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.dir.techniques="/var/rudder/configuration-repository/techniques" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.dir.uploaded.file.sharing="/var/rudder/files/" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.endpoint.cmdb="http://localhost:8080/endpoint/upload/" [2020-01-10 11:10:07+0100] 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" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.jdbc.batch.max.size="500" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.jdbc.driver="org.postgresql.Driver" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.jdbc.maxPoolSize="25" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.jdbc.password=********** [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.jdbc.url="jdbc:postgresql://localhost:5432/rudder" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.jdbc.username="rudder" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.jvm.fatal.exceptions="" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.rest.allowNonAuthenticatedUser="true" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.server-roles.cfengine-mission-portal="autodetect" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.server-roles.db="autodetect" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.server-roles.inventory-endpoint="autodetect" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.server-roles.ldap="autodetect" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.server-roles.relay-promises-only="autodetect" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.server-roles.relay-top="autodetect" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.server-roles.web="autodetect" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.server.relay.api="https://localhost/rudder/relay-api" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.syslog.port="514" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.techniqueLibrary.git.refs.path="refs/heads/master" [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.webdav.password=********** [2020-01-10 11:10:07+0100] INFO application - registered property: rudder.webdav.user="rudder" [2020-01-10 11:10:07+0100] INFO application - registered property: run.mode="production" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.arch.data.model="64" [2020-01-10 11:10:07+0100] 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" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.boot.library.path="/usr/lib64/jvm/java-1.8.0-openjdk-1.8.0/jre/lib/amd64" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.cpu.endian="little" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.cpu.isalist="" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.io.unicode.encoding="UnicodeLittle" [2020-01-10 11:10:07+0100] 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" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.java.launcher="SUN_STANDARD" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.jnu.encoding="ANSI_X3.4-1968" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.management.compiler="HotSpot 64-Bit Tiered Compilers" [2020-01-10 11:10:07+0100] INFO application - registered property: sun.os.patch.level="unknown" [2020-01-10 11:10:07+0100] INFO application - registered property: upload.root.directory="/var/rudder/files/" [2020-01-10 11:10:07+0100] INFO application - registered property: user.country="US" [2020-01-10 11:10:07+0100] INFO application - registered property: user.dir="/opt/rudder/etc/rudder-jetty-base" [2020-01-10 11:10:07+0100] INFO application - registered property: user.home="/root" [2020-01-10 11:10:07+0100] INFO application - registered property: user.language="en" [2020-01-10 11:10:07+0100] INFO application - registered property: user.name="root" [2020-01-10 11:10:07+0100] INFO application - registered property: user.timezone="Europe/Paris" [2020-01-10 11:10:07+0100] INFO application - registered property: waiting.inventory.queue.size="50" [2020-01-10 11:10:07+0100] INFO application - Plugin's license directory: '/opt/rudder/etc/plugins/licenses/' [2020-01-10 11:10:07+0100] INFO bootchecks - Check LDAP and PostgreSQL connection [2020-01-10 11:10:07+0100] INFO bootchecks - LDAP and PostgreSQL connection are OK [2020-01-10 11:10:07+0100] INFO bootchecks - Check mandatory DIT entries [2020-01-10 11:10:07+0100] INFO bootchecks - All the required DIT entries are present in the LDAP directory [2020-01-10 11:10:07+0100] INFO bootchecks - Check initialization of User Technique Library [2020-01-10 11:10:07+0100] INFO bootchecks - Check event log migration format 5 -> 6 [2020-01-10 11:10:08+0100] INFO bootchecks - Check existence of at least one archive of the configuration [2020-01-10 11:10:08+0100] INFO migration - No migration detected by migration script (table 'migrationeventlog' is empty or does not exist) [2020-01-10 11:10:08+0100] INFO bootchecks - First full archive of configuration-repository items done [2020-01-10 11:10:08+0100] INFO bootchecks - Check rules archive directory in configuration-repository [2020-01-10 11:10:08+0100] INFO bootchecks - Creating directory '/var/rudder/configuration-repository/ruleCategories' exists, done [2020-01-10 11:10:08+0100] INFO bootchecks - Check for force reload of Techniques library [2020-01-10 11:10:08+0100] INFO bootchecks - Flag file '/opt/rudder/etc/force_technique_reload' does not exist, do not Technique library will not be reloaded [2020-01-10 11:10:08+0100] INFO bootchecks - Check that system group / directive / rules for Rudder 4.2 are agent-specific [2020-01-10 11:10:08+0100] INFO bootchecks - Regenerate all ncf techniques [2020-01-10 11:10:08+0100] INFO bootchecks - Resume policy update if it was running before shutdown [2020-01-10 11:10:08+0100] INFO bootchecks - Flag file '/opt/rudder/etc/policy-update-running' found, Start a new policy update now [2020-01-10 11:10:08+0100] INFO bootchecks - Trigger policy update if it was requested during shutdown [2020-01-10 11:10:08+0100] INFO bootchecks - Create system api token [2020-01-10 11:10:08+0100] INFO bootchecks - System api token file created in /var/rudder/run/api-token [2020-01-10 11:10:08+0100] INFO bootchecks - Update existing API token to 'RW' autorization level. [2020-01-10 11:10:08+0100] INFO bootchecks - Check NashHorn JS engine for warning [2020-01-10 11:10:08+0100] INFO policy.generation - Start policy generation, checking updated rules [2020-01-10 11:10:09+0100] INFO application - Configured authentication provider(s): [rootAdmin, file] [2020-01-10 11:10:09+0100] 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 [2020-01-10 11:10:09+0100] INFO application - Add backend providers 'Default authentication backends provider: 'file','rootAdmin' [2020-01-10 11:10:09+0100] INFO application - Using configuration file defined by JVM property -Drudder.authFile : /opt/rudder/etc/rudder-users.xml [2020-01-10 11:10:09+0100] INFO policy.generation - [metrics] Xmx:1007 MB nodes:1 (cached:1) rules:5 (enabled:4) techniques:44 (enabled:44) directives:4 (enabled:4) groups:2 (dynamic:2) parameters:1 [2020-01-10 11:10:09+0100] ERROR com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl - SystemError: An error occured; cause was: java.nio.file.NoSuchFileException: /var/rudder/lib/ssl/allnodescerts.pem.new -> com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl.$anonfun$writeCertificates$6(WriteNodeCertificatesPem.scala:98) [2020-01-10 11:10:09+0100] INFO bootstrap.liftweb.Boot - classpath [2020-01-10 11:10:09+0100] INFO org.reflections.Reflections - Reflections took 124 ms to scan 124 urls, producing 0 keys and 0 values INFO Configure inventory processing with parallelism of '1' and queue size of '50' [2020-01-10 11:10:10+0100] INFO inventory-processing - Incoming inventory watcher started - process existing inventories [2020-01-10 11:10:10+0100] INFO application - Application Rudder started 2020-01-10 11:10:10.835:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@6c3708b3{/rudder,file:///var/rudder/tmp/jetty/jetty-rudder.war.dir/webapp/,AVAILABLE}{/rudder.war} 2020-01-10 11:10:10.869:INFO:oejs.AbstractConnector:main: Started ServerConnector@3830f918{HTTP/1.1,[http/1.1]}{127.0.0.1:8080} 2020-01-10 11:10:10.877:INFO:oejs.Server:main: Started @10883ms [2020-01-10 11:10:11+0100] INFO policy.generation - Configuration of following 1 nodes were updated, their policies are going to be written: [root] [2020-01-10 11:10:12+0100] INFO policy.generation - Policy generation failed after: 4 s [2020-01-10 11:10:12+0100] ERROR policy.generation - Error when updating policy, reason was: Cannot write nodes configuration <- Error when executing hooks: Exit code=1 for hook: '/opt/rudder/etc/hooks.d/policy-generation-node-ready/10-cf-promise-check'. [stdout:][stderr:/var/rudder/ncf/common/20_cfe_basics/common.cf:20:0: error: Duplicate definition of bundle ncf_const with type common /var/rudder/ncf/common/20_cfe_basics/common.cf:233:0: error: Duplicate definition of bundle ncf_classes_combine_two with type agent /var/rudder/ncf/common/20_cfe_basics/common.cf:...] (for node(s) root) [2020-01-10 11:10:12+0100] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed [2020-01-10 11:10:12+0100] ERROR policy.generation - Policy update error for process '3' at 2020-01-10 11:10:12: Cannot write nodes configuration [2020-01-10 11:10:18+0100] INFO compliance - [metrics] global compliance (number of components): undefined [2020-01-10 11:10:20+0100] INFO com.normation.cfclerk.services.impl.TechniqueRepositoryImpl - Reloading technique library, no modified techniques found [2020-01-10 11:10:20+0100] INFO com.normation.rudder.services.policies.DeployOnTechniqueCallback - Update Technique library after updating all techniques at start up [2020-01-10 11:10:20+0100] INFO bootchecks - All ncf techniques were updated, 0 were migrated [2020-01-10 11:10:34+0100] INFO policy.generation - Start policy generation, checking updated rules [2020-01-10 11:10:34+0100] INFO policy.generation - [metrics] Xmx:1007 MB nodes:1 (cached:0) rules:5 (enabled:4) techniques:44 (enabled:44) directives:4 (enabled:4) groups:2 (dynamic:2) parameters:1 [2020-01-10 11:10:34+0100] ERROR com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl - SystemError: An error occured; cause was: java.nio.file.NoSuchFileException: /var/rudder/lib/ssl/allnodescerts.pem.new -> com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl.$anonfun$writeCertificates$6(WriteNodeCertificatesPem.scala:98) [2020-01-10 11:10:34+0100] INFO policy.generation - Configuration of following 1 nodes were updated, their policies are going to be written: [root] [2020-01-10 11:10:35+0100] INFO policy.generation - Policy generation failed after: 1 s [2020-01-10 11:10:35+0100] ERROR policy.generation - Error when updating policy, reason was: Cannot write nodes configuration <- Error when executing hooks: Exit code=1 for hook: '/opt/rudder/etc/hooks.d/policy-generation-node-ready/10-cf-promise-check'. [stdout:][stderr:/var/rudder/ncf/common/20_cfe_basics/common.cf:20:0: error: Duplicate definition of bundle ncf_const with type common /var/rudder/ncf/common/20_cfe_basics/common.cf:233:0: error: Duplicate definition of bundle ncf_classes_combine_two with type agent /var/rudder/ncf/common/20_cfe_basics/common.cf:...] (for node(s) root) [2020-01-10 11:10:35+0100] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed [2020-01-10 11:10:35+0100] ERROR policy.generation - Policy update error for process '4' at 2020-01-10 11:10:35: Cannot write nodes configuration [2020-01-10 11:11:01+0100] INFO compliance - [metrics] global compliance (number of components): undefined [2020-01-10 11:11:05+0100] INFO policy.generation - Start policy generation, checking updated rules [2020-01-10 11:11:05+0100] INFO policy.generation - [metrics] Xmx:1007 MB nodes:1 (cached:0) rules:5 (enabled:4) techniques:44 (enabled:44) directives:4 (enabled:4) groups:2 (dynamic:2) parameters:1 [2020-01-10 11:11:05+0100] ERROR com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl - SystemError: An error occured; cause was: java.nio.file.NoSuchFileException: /var/rudder/lib/ssl/allnodescerts.pem.new -> com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl.$anonfun$writeCertificates$6(WriteNodeCertificatesPem.scala:98) [2020-01-10 11:11:05+0100] INFO policy.generation - Configuration of following 1 nodes were updated, their policies are going to be written: [root] [2020-01-10 11:11:07+0100] INFO policy.generation - Policy generation failed after: 1 s [2020-01-10 11:11:07+0100] ERROR policy.generation - Error when updating policy, reason was: Exit code=1 for hook: '/opt/rudder/etc/hooks.d/policy-generation-finished/50-reload-policy-file-server'. stdout: stderr: 'Job for rudder-cf-serverd.service failed. See "systemctl status rudder-cf-serverd.service" and "journalctl -xn" for details. ' [2020-01-10 11:11:07+0100] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed [2020-01-10 11:11:07+0100] ERROR policy.generation - Policy update error for process '5' at 2020-01-10 11:11:07: Exit code=1 for hook: '/opt/rudder/etc/hooks.d/policy-generation-finished/50-reload-policy-file-server'. stdout: stderr: 'Job for rudder-cf-serverd.service failed. See "systemctl status rudder-cf-serverd.service" and "journalctl -xn" for details. ' [2020-01-10 11:13:18+0100] INFO policy.generation - Start policy generation, checking updated rules [2020-01-10 11:13:18+0100] INFO policy.generation - [metrics] Xmx:1007 MB nodes:1 (cached:0) rules:5 (enabled:4) techniques:44 (enabled:44) directives:4 (enabled:4) groups:2 (dynamic:2) parameters:1 [2020-01-10 11:13:18+0100] ERROR com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl - SystemError: An error occured; cause was: java.nio.file.NoSuchFileException: /var/rudder/lib/ssl/allnodescerts.pem.new -> com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl.$anonfun$writeCertificates$6(WriteNodeCertificatesPem.scala:98) [2020-01-10 11:13:18+0100] INFO policy.generation - Configuration of following 1 nodes were updated, their policies are going to be written: [root] [2020-01-10 11:13:19+0100] INFO policy.generation - Timing summary: [2020-01-10 11:13:19+0100] INFO policy.generation - Run pre-gen scripts hooks : 2 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Run pre-gen modules hooks : 0 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Fetch all information : 46 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Historize names : 29 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Build current rule values : 1 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Build target configuration : 12 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Write node configurations : 1152 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Save expected reports : 0 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Run post generation hooks : 45 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Number of nodes updated : 1 [2020-01-10 11:13:19+0100] INFO policy.generation - Policy generation succeeded in: 1 s [2020-01-10 11:13:19+0100] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed [2020-01-10 11:13:19+0100] INFO policy.generation - Successful policy update '6' [started 2020-01-10 11:13:18 - ended 2020-01-10 11:13:19] [2020-01-10 11:13:19+0100] INFO policy.generation - Start policy generation, checking updated rules [2020-01-10 11:13:19+0100] INFO policy.generation - [metrics] Xmx:1007 MB nodes:1 (cached:1) rules:5 (enabled:4) techniques:44 (enabled:44) directives:4 (enabled:4) groups:2 (dynamic:2) parameters:1 [2020-01-10 11:13:19+0100] ERROR com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl - SystemError: An error occured; cause was: java.nio.file.NoSuchFileException: /var/rudder/lib/ssl/allnodescerts.pem.new -> com.normation.rudder.services.policies.WriteNodeCertificatesPemImpl.$anonfun$writeCertificates$6(WriteNodeCertificatesPem.scala:98) [2020-01-10 11:13:19+0100] INFO policy.generation - No node configuration was updated, no policies to write [2020-01-10 11:13:19+0100] INFO policy.generation - Timing summary: [2020-01-10 11:13:19+0100] INFO policy.generation - Run pre-gen scripts hooks : 2 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Run pre-gen modules hooks : 0 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Fetch all information : 46 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Historize names : 34 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Build current rule values : 1 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Build target configuration : 12 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Write node configurations : 7 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Save expected reports : 0 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Run post generation hooks : 77 ms [2020-01-10 11:13:19+0100] INFO policy.generation - Number of nodes updated : 0 [2020-01-10 11:13:19+0100] INFO policy.generation - Policy generation succeeded in: [2020-01-10 11:13:19+0100] INFO policy.generation - Flag file '/opt/rudder/etc/policy-update-running' successfully removed [2020-01-10 11:13:19+0100] INFO policy.generation - Successful policy update '7' [started 2020-01-10 11:13:19 - ended 2020-01-10 11:13:19] [2020-01-10 11:13:23+0100] INFO compliance - [metrics] global compliance (number of components): undefined [2020-01-10 11:13:40+0100] INFO inventory-processing - Watch new inventory file 'server-root.ocs' with signature available: process. [2020-01-10 11:13:42+0100] INFO inventory-processing - Report 'server-root.ocs' for node 'server.rudder.local' [root] (signature:certified) processed in 652 milliseconds ms
Actions