Project

General

Profile

Actions

Bug #16520

closed

WriteNodeCertificatesPemImpl error after upgrade from 5.0 to 6.0

Added by Félix DALLIDET over 4 years ago. Updated about 4 years ago.

Status:
Rejected
Priority:
N/A
Assignee:
-
Category:
Server components
Target version:
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 #1

Updated by Vincent MEMBRÉ about 4 years ago

  • Target version changed from 6.0.3 to 6.0.4
Actions #2

Updated by François ARMAND about 4 years ago

  • Status changed from New to Rejected

We weren't able to reproduce that, and a lot of correction were done on upgrade. If anybody finds it, please reopen.

Actions

Also available in: Atom PDF