Project

General

Profile

Actions

Bug #10785

closed

With initial promises, cf-execd is not restarted

Added by François ARMAND almost 7 years ago. Updated over 4 years ago.

Status:
Rejected
Priority:
N/A
Assignee:
-
Category:
Agent
Target version:
Severity:
Minor - inconvenience | misleading | easy workaround
UX impact:
User visibility:
Getting started - demo | first install | level 1 Techniques
Effort required:
Priority:
0
Name check:
Fix check:
Regression:

Description

I have a test case on debian 8, on https://app.packet.net where after the installation, before acceptation, cf-execd was not started.

I could see in /var/log/syslog agent runs every 5 minutes like that (node not accepted yet):

May 24 14:55:01 agent1 CRON[7092]: (root) CMD (/opt/rudder/bin/rudder agent check >/dev/null)
May 24 14:55:01 agent1 CRON[7093]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
May 24 14:55:01 agent1 rudder[7160]: CFEngine(agent) rudder Failed to establish TLS connection: underlying network error (Connection reset by peer)
May 24 14:55:01 agent1 rudder[7160]: CFEngine(agent) rudder No suitable server found
May 24 14:55:01 agent1 rudder[7160]: CFEngine(agent) rudder Failed to establish TLS connection: underlying network error (Connection reset by peer)
May 24 14:55:01 agent1 rudder[7160]: CFEngine(agent) rudder No suitable server found
May 24 14:55:01 agent1 rudder[7160]: CFEngine(agent) rudder R: *********************************************************************************#012* rudder-agent could not get an updated configuration from the policy server.   *#012* This can be caused by:                                                        *#012*   * an incorrect DNS resolution of this node                                  *#012*   * an agent key that has been changed                                        *#012*   * if this node is not accepted or deleted node on the Rudder root server    *#012*   * if this node has changed policy server without sending a new inventory    *#012* Any existing configuration policy will continue to be applied without change. *#012*********************************************************************************
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@log_info@@hasPolicyServer-root@@common-root@@00@@common@@StartRun@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Start execution with config [0]
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@result_error@@hasPolicyServer-root@@common-root@@00@@Update@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Cannot update tools (update flag file)
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@result_error@@hasPolicyServer-root@@common-root@@00@@Update@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Cannot update configuration policy
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: *********************************************************************************#012  * rudder-agent could not get an updated configuration from the policy server.   *#012  * This can be caused by a network issue, an unavailable server, or if this      *#012  * node was deleted from the Rudder root server.                                 *#012  * Any existing configuration policy will continue to be applied without change. *#012  *********************************************************************************
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder Unable to open template file '/var/rudder/cfengine-community/inputs/rudder_expected_reports.csv' to make '/var/rudder/cfengine-community/state/rudder_expected_reports.7171.csv.tmp'
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@result_success@@hasPolicyServer-root@@common-root@@00@@ncf Initialization@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Configuration library initialization was correct
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@result_success@@hasPolicyServer-root@@common-root@@00@@Security parameters@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#The internal environment security is acceptable
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@result_success@@hasPolicyServer-root@@common-root@@00@@Red Button@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Red Button is not in effect, continuing as normal...
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@result_na@@hasPolicyServer-root@@common-root@@00@@Process checking@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Rudder agent proccesses check is done by the rudder-agent cron job
May 24 14:55:03 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@result_success@@hasPolicyServer-root@@common-root@@00@@CRON Daemon@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Cron daemon status was correct
May 24 14:55:05 agent1 rudder[7171]: CFEngine(agent) rudder Q: "...-inventory --lo": [info] FusionInventory instance: builtin#012Q: "...-inventory --lo": [info] running task Inventory#012Q: "...-inventory --lo": Use of uninitialized value $FQDN in string eq at /opt/rudder/share/fusioninventory/lib/FusionInventory/Agent/Task/Inventory/Generic.pm line 21.#012Q: "...-inventory --lo": [info] Inventory saved in /var/rudder/tmp/inventory/agent1-8b099c38-5d93-4524-bb3e-bd28600c4c59.ocs#012Q: "...-inventory --lo": [error] Can't locate object method "isEnabled" via package "FusionInventory::Agent::Task::Deploy" at /opt/rudder/share/fusioninventory/lib/FusionInventory/Agent.pm line 473.
May 24 14:55:05 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Inventory@@log_debug@@inventory-all@@inventory-all@@00@@inventory@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Running inventory
May 24 14:55:05 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Inventory@@log_info@@inventory-all@@inventory-all@@00@@inventory@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Curl installed
May 24 14:55:05 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Inventory@@log_debug@@inventory-all@@inventory-all@@00@@inventory@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#User list generation tool is not present yet. Skipping...
May 24 14:55:06 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Inventory@@log_debug@@inventory-all@@inventory-all@@00@@inventory@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#CPUID generation tool not available for this platform, or not present yet. Skipping...
May 24 14:55:07 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Inventory@@result_error@@inventory-all@@inventory-all@@00@@inventory@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Generated inventory has been detected as invalid
May 24 14:55:07 agent1 rudder[7171]: CFEngine(agent) rudder R: ********************************************************************************#012* WARNING Generated inventory is not valid, as it is missing mandatory fields. *#012* Not sending it to the Rudder Server                                          *#012* You can get a full error message by running: /opt/rudder/bin/perl -I /opt/rudder/lib/perl5 /var/rudder/cfengine-community/inputs/inventory/1.0/test-inventory.pl /var/rudder/tmp/inventory/agent1-8b099c38-5d93-4524-bb3e-bd28600c4c59.ocs #012********************************************************************************
May 24 14:55:07 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Inventory@@result_success@@inventory-all@@inventory-all@@00@@inventory@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#Next inventory scheduled between 00:00 and 06:00
May 24 14:55:07 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@result_success@@hasPolicyServer-root@@common-root@@00@@Binaries update@@None@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#The agent binaries in /var/rudder/cfengine-community/bin are up to date
May 24 14:55:07 agent1 rudder[7171]: CFEngine(agent) rudder R: @@Common@@log_info@@hasPolicyServer-root@@common-root@@00@@common@@EndRun@@2017-05-24 14:55:02+00:00##8b099c38-5d93-4524-bb3e-bd28600c4c59@#End execution with config [0]

But /var/rudder/cfengine-community/outputs/ remained empty.

As soon as the initial promise were replaced by the updated promises for the node, cf-execd was restarted.
After the first run after acceptation, log started comming to /var/rudder/cfengine-community/outputs/

Hypothesis is that as cf-execd was not started, every 5 minutes rudder check was running cf-agent (so no reports in outputs) and that something in the initial promises was preventing cf-execd to be restarted (something to with the file about run frequency not present?)

Actions #1

Updated by François ARMAND almost 7 years ago

  • Priority changed from 0 to 35
Actions #2

Updated by Vincent MEMBRÉ almost 7 years ago

  • Target version changed from 4.1.4 to 4.1.5
Actions #3

Updated by Alexis Mousset almost 7 years ago

  • Target version changed from 4.1.5 to 4.1.6
Actions #4

Updated by Benoît PECCATTE almost 7 years ago

  • Priority changed from 35 to 50
Actions #5

Updated by Vincent MEMBRÉ over 6 years ago

  • Target version changed from 4.1.6 to 4.1.7
  • Priority changed from 50 to 49
Actions #6

Updated by Vincent MEMBRÉ over 6 years ago

  • Target version changed from 4.1.7 to 4.1.8
Actions #7

Updated by Vincent MEMBRÉ over 6 years ago

  • Target version changed from 4.1.8 to 4.1.9
  • Priority changed from 49 to 47
Actions #8

Updated by Vincent MEMBRÉ over 6 years ago

  • Target version changed from 4.1.9 to 4.1.10
  • Priority changed from 47 to 46
Actions #9

Updated by Vincent MEMBRÉ about 6 years ago

  • Target version changed from 4.1.10 to 4.1.11
  • Priority changed from 46 to 44
Actions #10

Updated by Vincent MEMBRÉ almost 6 years ago

  • Target version changed from 4.1.11 to 4.1.12
  • Priority changed from 44 to 43
Actions #11

Updated by Vincent MEMBRÉ almost 6 years ago

  • Target version changed from 4.1.12 to 4.1.13
  • Priority changed from 43 to 42
Actions #12

Updated by Benoît PECCATTE almost 6 years ago

  • Target version changed from 4.1.13 to 411
Actions #13

Updated by Benoît PECCATTE almost 6 years ago

  • Target version changed from 411 to 4.1.13
Actions #14

Updated by Vincent MEMBRÉ over 5 years ago

  • Target version changed from 4.1.13 to 4.1.14
Actions #15

Updated by Benoît PECCATTE over 5 years ago

  • Target version changed from 4.1.14 to 4.1.15
Actions #16

Updated by Vincent MEMBRÉ over 5 years ago

  • Target version changed from 4.1.15 to 4.1.16
Actions #17

Updated by Vincent MEMBRÉ over 5 years ago

  • Target version changed from 4.1.16 to 4.1.17
Actions #18

Updated by Vincent MEMBRÉ over 5 years ago

  • Target version changed from 4.1.17 to 4.1.18
  • Priority changed from 42 to 0
Actions #19

Updated by Vincent MEMBRÉ about 5 years ago

  • Target version changed from 4.1.18 to 4.1.19
Actions #20

Updated by Alexis Mousset about 5 years ago

  • Target version changed from 4.1.19 to 4.1.20
Actions #21

Updated by François ARMAND about 5 years ago

  • Target version changed from 4.1.20 to 4.1.21
Actions #22

Updated by Vincent MEMBRÉ almost 5 years ago

  • Target version changed from 4.1.21 to 4.1.22
Actions #23

Updated by Vincent MEMBRÉ almost 5 years ago

  • Target version changed from 4.1.22 to 4.1.23
Actions #24

Updated by Vincent MEMBRÉ almost 5 years ago

  • Target version changed from 4.1.23 to 4.1.24
Actions #25

Updated by Vincent MEMBRÉ over 4 years ago

  • Target version changed from 4.1.24 to 588
Actions #26

Updated by François ARMAND over 4 years ago

  • Status changed from New to Rejected

We never saw that happen again in Rudder 5.0, so I'm closing that ticket.

Actions #27

Updated by Alexis Mousset over 4 years ago

  • Target version changed from 588 to 4.1.24
Actions

Also available in: Atom PDF